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]
12:43:31 INFO - 1500147811864 Marionette TRACE 353 -> [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}]
12:43:31 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500147811866 Marionette TRACE 353 <- [1,5,null,{"value":null}]
12:43:31 INFO - 1500147811866 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
12:43:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 139ms
12:43:31 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:43:31 INFO - 1500147811868 Marionette TRACE 353 <- [1,6,null,{}]
12:43:31 INFO - 1500147811869 Marionette DEBUG Closed connection 353
12:43:31 INFO - 1500147811870 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49769
12:43:31 INFO - 1500147811871 Marionette DEBUG Closed connection 354
12:43:31 INFO - 1500147811871 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49770
12:43:31 INFO - 1500147811872 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:31 INFO - 1500147811873 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:31 INFO - 1500147811875 Marionette DEBUG Register listener.js for window 15
12:43:31 INFO - 1500147811877 Marionette TRACE 355 <- [1,1,null,{"sessionId":"4c854eb1-ed2e-4474-820a-087361f1ddbd","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:31 INFO - 1500147811879 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
12:43:31 INFO - 1500147811880 Marionette TRACE 355 <- [1,2,null,{}]
12:43:31 INFO - 1500147811881 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:31 INFO - 1500147811881 Marionette TRACE 355 <- [1,3,null,{}]
12:43:31 INFO - 1500147811882 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:31 INFO - 1500147811882 Marionette TRACE 355 <- [1,4,null,{}]
12:43:31 INFO - 1500147811883 Marionette TRACE 355 -> [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}]
12:43:31 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500147811885 Marionette TRACE 355 <- [1,5,null,{"value":null}]
12:43:31 INFO - 1500147811886 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
12:43:31 INFO - 1500147811887 Marionette TRACE 355 <- [1,6,null,{}]
12:43:31 INFO - 1500147811889 Marionette DEBUG Closed connection 355
12:43:31 INFO - 1500147811889 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49771
12:43:31 INFO - 1500147811890 Marionette DEBUG Closed connection 356
12:43:31 INFO - 1500147811890 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49772
12:43:31 INFO - 1500147811891 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:31 INFO - 1500147811892 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:31 INFO - 1500147811894 Marionette DEBUG Register listener.js for window 15
12:43:31 INFO - 1500147811896 Marionette TRACE 357 <- [1,1,null,{"sessionId":"289dacf4-7f0b-440f-b179-d34a6bc70db5","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:31 INFO - 1500147811898 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
12:43:31 INFO - 1500147811899 Marionette TRACE 357 <- [1,2,null,{}]
12:43:31 INFO - 1500147811901 Marionette DEBUG Closed connection 357
12:43:31 INFO - 1500147811901 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49773
12:43:31 INFO - 1500147811902 Marionette DEBUG Closed connection 358
12:43:31 INFO - 1500147811902 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49774
12:43:31 INFO - 1500147811903 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:31 INFO - 1500147811904 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:31 INFO - 1500147811906 Marionette DEBUG Register listener.js for window 15
12:43:31 INFO - 1500147811908 Marionette TRACE 359 <- [1,1,null,{"sessionId":"0cf7bda4-10dd-43f1-895a-dffe54220db5","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:31 INFO - 1500147811910 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49336/clicks.html"}]
12:43:31 INFO - 1500147811911 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/hidden.html"
12:43:31 INFO - 1500147811916 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/hidden.html"
12:43:31 INFO - 1500147811916 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/hidden.html"
12:43:31 INFO - 1500147811933 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/clicks.html"
12:43:31 INFO - 1500147811934 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/clicks.html"
12:43:31 INFO - 1500147811937 Marionette TRACE 359 <- [1,2,null,{}]
12:43:31 INFO - 1500147811938 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:43:31 INFO - 1500147811940 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e5a5ae43-6426-41b0-8425-b2d616a93724","ELEMENT":"e5a5ae43-6426-41b0-8425-b2d616a93724"}}]
12:43:31 INFO - 1500147811947 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"e5a5ae43-6426-41b0-8425-b2d616a93724"}]
12:43:31 INFO - 1500147811992 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/clicks.html"
12:43:32 INFO - 1500147811999 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/clicks.html"
12:43:32 INFO - 1500147812000 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/clicks.html"
12:43:32 INFO - 1500147812015 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/test.html"
12:43:32 INFO - 1500147812016 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/test.html"
12:43:32 INFO - 1500147812018 Marionette TRACE 359 <- [1,4,null,{}]
12:43:32 INFO - 1500147812020 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:43:32 INFO - 1500147812022 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e586eaa1-eda3-4513-a43a-442c470f5ac0","ELEMENT":"e586eaa1-eda3-4513-a43a-442c470f5ac0"}}]
12:43:32 INFO - 1500147812022 Marionette TRACE 359 -> [0,6,"getTitle",{}]
12:43:32 INFO - 1500147812023 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
12:43:32 INFO - 1500147812024 Marionette TRACE 359 -> [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}]
12:43:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 167ms
12:43:32 INFO - TEST-START | test_click.py TestClick.test_container_element
12:43:32 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500147812032 Marionette TRACE 359 <- [1,7,null,{"value":null}]
12:43:32 INFO - 1500147812033 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
12:43:32 INFO - 1500147812035 Marionette TRACE 359 <- [1,8,null,{}]
12:43:32 INFO - 1500147812036 Marionette DEBUG Closed connection 359
12:43:32 INFO - 1500147812037 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49777
12:43:32 INFO - 1500147812038 Marionette DEBUG Closed connection 360
12:43:32 INFO - 1500147812038 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49778
12:43:32 INFO - 1500147812039 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:32 INFO - 1500147812040 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812042 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812045 Marionette TRACE 361 <- [1,1,null,{"sessionId":"1c9be8c7-bdb0-42fe-beaa-38237bd8ba6f","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812047 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
12:43:32 INFO - 1500147812047 Marionette TRACE 361 <- [1,2,null,{}]
12:43:32 INFO - 1500147812048 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:32 INFO - 1500147812048 Marionette TRACE 361 <- [1,3,null,{}]
12:43:32 INFO - 1500147812049 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:32 INFO - 1500147812049 Marionette TRACE 361 <- [1,4,null,{}]
12:43:32 INFO - 1500147812051 Marionette TRACE 361 -> [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}]
12:43:32 INFO - TEST-START: test_click.py TestClick.test_container_element1500147812053 Marionette TRACE 361 <- [1,5,null,{"value":null}]
12:43:32 INFO - 1500147812054 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
12:43:32 INFO - 1500147812055 Marionette TRACE 361 <- [1,6,null,{}]
12:43:32 INFO - 1500147812056 Marionette DEBUG Closed connection 361
12:43:32 INFO - 1500147812057 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49779
12:43:32 INFO - 1500147812057 Marionette DEBUG Closed connection 362
12:43:32 INFO - 1500147812058 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49780
12:43:32 INFO - 1500147812059 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:32 INFO - 1500147812060 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812062 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812065 Marionette TRACE 363 <- [1,1,null,{"sessionId":"873e5c78-fe4c-4c71-a6e5-aa6185dc4b45","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812066 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
12:43:32 INFO - 1500147812067 Marionette TRACE 363 <- [1,2,null,{}]
12:43:32 INFO - 1500147812069 Marionette DEBUG Closed connection 363
12:43:32 INFO - 1500147812069 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49781
12:43:32 INFO - 1500147812070 Marionette DEBUG Closed connection 364
12:43:32 INFO - 1500147812071 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49782
12:43:32 INFO - 1500147812072 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:32 INFO - 1500147812072 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812074 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812077 Marionette TRACE 365 <- [1,1,null,{"sessionId":"d13b475c-1d10-4560-a725-b23287efec3b","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812079 Marionette TRACE 365 -> [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"}]
12:43:32 INFO - 1500147812080 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/test.html"
12:43:32 INFO - 1500147812082 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/test.html"
12:43:32 INFO - 1500147812082 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/test.html"
12:43:32 INFO - 1500147812100 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"
12:43:32 INFO - 1500147812103 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"
12:43:32 INFO - 1500147812106 Marionette TRACE 365 <- [1,2,null,{}]
12:43:32 INFO - 1500147812108 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:43:32 INFO - 1500147812117 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5b91496b-63bc-4555-a315-f2de0142f077","ELEMENT":"5b91496b-63bc-4555-a315-f2de0142f077"}}]
12:43:32 INFO - 1500147812118 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"5b91496b-63bc-4555-a315-f2de0142f077"}]
12:43:32 INFO - 1500147812356 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:32 INFO - 1500147812357 Marionette TRACE 365 <- [1,4,null,{}]
12:43:32 INFO - 1500147812358 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"5b91496b-63bc-4555-a315-f2de0142f077","name":"selected"}]
12:43:32 INFO - 1500147812362 Marionette TRACE 365 <- [1,5,null,{"value":true}]
12:43:32 INFO - 1500147812364 Marionette TRACE 365 -> [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}]
12:43:32 INFO - TEST-END: test_click.py TestClick.test_container_element1500147812366 Marionette TRACE 365 <- [1,6,null,{"value":null}]
12:43:32 INFO - 1500147812367 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
12:43:32 INFO - 1500147812368 Marionette TRACE 365 <- [1,7,null,{}]
12:43:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 333ms
12:43:32 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:43:32 INFO - 1500147812370 Marionette DEBUG Closed connection 365
12:43:32 INFO - 1500147812371 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49783
12:43:32 INFO - 1500147812371 Marionette DEBUG Closed connection 366
12:43:32 INFO - 1500147812372 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49784
12:43:32 INFO - 1500147812373 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:32 INFO - 1500147812373 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812375 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812378 Marionette TRACE 367 <- [1,1,null,{"sessionId":"0f498918-bea1-4b0a-9469-78335a7435c7","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812380 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
12:43:32 INFO - 1500147812380 Marionette TRACE 367 <- [1,2,null,{}]
12:43:32 INFO - 1500147812381 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:32 INFO - 1500147812381 Marionette TRACE 367 <- [1,3,null,{}]
12:43:32 INFO - 1500147812382 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:32 INFO - 1500147812383 Marionette TRACE 367 <- [1,4,null,{}]
12:43:32 INFO - 1500147812385 Marionette TRACE 367 -> [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}]
12:43:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500147812387 Marionette TRACE 367 <- [1,5,null,{"value":null}]
12:43:32 INFO - 1500147812388 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
12:43:32 INFO - 1500147812389 Marionette TRACE 367 <- [1,6,null,{}]
12:43:32 INFO - 1500147812391 Marionette DEBUG Closed connection 367
12:43:32 INFO - 1500147812391 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49785
12:43:32 INFO - 1500147812392 Marionette DEBUG Closed connection 368
12:43:32 INFO - 1500147812392 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49786
12:43:32 INFO - 1500147812393 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:32 INFO - 1500147812394 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812396 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812399 Marionette TRACE 369 <- [1,1,null,{"sessionId":"09e22825-7a1b-4c13-9a7c-ce44d9ec867f","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812401 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
12:43:32 INFO - 1500147812402 Marionette TRACE 369 <- [1,2,null,{}]
12:43:32 INFO - 1500147812403 Marionette DEBUG Closed connection 369
12:43:32 INFO - 1500147812404 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49787
12:43:32 INFO - 1500147812405 Marionette DEBUG Closed connection 370
12:43:32 INFO - 1500147812406 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49788
12:43:32 INFO - 1500147812407 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:32 INFO - 1500147812408 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812410 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812419 Marionette TRACE 371 <- [1,1,null,{"sessionId":"9d969fe5-9191-449f-9751-72abe05784c3","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812422 Marionette TRACE 371 -> [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"}]
12:43:32 INFO - 1500147812423 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"
12:43:32 INFO - 1500147812425 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"
12:43:32 INFO - 1500147812425 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"
12:43:32 INFO - 1500147812441 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"
12:43:32 INFO - 1500147812446 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"
12:43:32 INFO - 1500147812449 Marionette TRACE 371 <- [1,2,null,{}]
12:43:32 INFO - 1500147812450 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:43:32 INFO - 1500147812452 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b1780941-aef0-437c-8b3f-e9dacf2cb27b","ELEMENT":"b1780941-aef0-437c-8b3f-e9dacf2cb27b"}}]
12:43:32 INFO - 1500147812453 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"b1780941-aef0-437c-8b3f-e9dacf2cb27b"}]
12:43:32 INFO - 1500147812696 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:32 INFO - 1500147812696 Marionette TRACE 371 <- [1,4,null,{}]
12:43:32 INFO - 1500147812697 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"b1780941-aef0-437c-8b3f-e9dacf2cb27b","name":"selected"}]
12:43:32 INFO - 1500147812701 Marionette TRACE 371 <- [1,5,null,{"value":true}]
12:43:32 INFO - 1500147812703 Marionette TRACE 371 -> [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}]
12:43:32 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500147812704 Marionette TRACE 371 <- [1,6,null,{"value":null}]
12:43:32 INFO - 1500147812705 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
12:43:32 INFO - 1500147812707 Marionette TRACE 371 <- [1,7,null,{}]
12:43:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 338ms
12:43:32 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:43:32 INFO - 1500147812708 Marionette DEBUG Closed connection 371
12:43:32 INFO - 1500147812710 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49789
12:43:32 INFO - 1500147812710 Marionette DEBUG Closed connection 372
12:43:32 INFO - 1500147812711 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49790
12:43:32 INFO - 1500147812712 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:32 INFO - 1500147812712 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812714 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812716 Marionette TRACE 373 <- [1,1,null,{"sessionId":"458f9d22-956b-4aa5-ad0a-3641b34071ec","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812718 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
12:43:32 INFO - 1500147812718 Marionette TRACE 373 <- [1,2,null,{}]
12:43:32 INFO - 1500147812719 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:32 INFO - 1500147812720 Marionette TRACE 373 <- [1,3,null,{}]
12:43:32 INFO - 1500147812721 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:32 INFO - 1500147812721 Marionette TRACE 373 <- [1,4,null,{}]
12:43:32 INFO - 1500147812723 Marionette TRACE 373 -> [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}]
12:43:32 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500147812725 Marionette TRACE 373 <- [1,5,null,{"value":null}]
12:43:32 INFO - 1500147812725 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
12:43:32 INFO - 1500147812726 Marionette TRACE 373 <- [1,6,null,{}]
12:43:32 INFO - 1500147812728 Marionette DEBUG Closed connection 373
12:43:32 INFO - 1500147812728 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49791
12:43:32 INFO - 1500147812729 Marionette DEBUG Closed connection 374
12:43:32 INFO - 1500147812730 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49792
12:43:32 INFO - 1500147812730 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:32 INFO - 1500147812731 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812733 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812735 Marionette TRACE 375 <- [1,1,null,{"sessionId":"e7959190-2ef0-4114-a8a3-01dcfc77a4be","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812737 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
12:43:32 INFO - 1500147812738 Marionette TRACE 375 <- [1,2,null,{}]
12:43:32 INFO - 1500147812740 Marionette DEBUG Closed connection 375
12:43:32 INFO - 1500147812740 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49793
12:43:32 INFO - 1500147812741 Marionette DEBUG Closed connection 376
12:43:32 INFO - 1500147812741 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49794
12:43:32 INFO - 1500147812742 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:32 INFO - 1500147812743 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:32 INFO - 1500147812745 Marionette DEBUG Register listener.js for window 15
12:43:32 INFO - 1500147812747 Marionette TRACE 377 <- [1,1,null,{"sessionId":"4df6bd37-97d8-4f54-9532-9fd4b5ee1302","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:32 INFO - 1500147812749 Marionette TRACE 377 -> [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"}]
12:43:32 INFO - 1500147812750 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"
12:43:32 INFO - 1500147812752 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"
12:43:32 INFO - 1500147812752 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"
12:43:32 INFO - 1500147812770 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"
12:43:32 INFO - 1500147812772 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"
12:43:32 INFO - 1500147812774 Marionette TRACE 377 <- [1,2,null,{}]
12:43:32 INFO - 1500147812780 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:43:32 INFO - 1500147812783 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2a233584-edca-4d98-8dfd-4473265c5ef6","ELEMENT":"2a233584-edca-4d98-8dfd-4473265c5ef6"}}]
12:43:32 INFO - 1500147812784 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"2a233584-edca-4d98-8dfd-4473265c5ef6"}]
12:43:33 INFO - 1500147813026 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:33 INFO - 1500147813027 Marionette TRACE 377 <- [1,4,null,{}]
12:43:33 INFO - 1500147813029 Marionette TRACE 377 -> [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}]
12:43:33 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500147813031 Marionette TRACE 377 <- [1,5,null,{"value":null}]
12:43:33 INFO - 1500147813032 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
12:43:33 INFO - 1500147813033 Marionette TRACE 377 <- [1,6,null,{}]
12:43:33 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 325ms
12:43:33 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:43:33 INFO - 1500147813035 Marionette DEBUG Closed connection 377
12:43:33 INFO - 1500147813035 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49795
12:43:33 INFO - 1500147813036 Marionette DEBUG Closed connection 378
12:43:33 INFO - 1500147813037 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49796
12:43:33 INFO - 1500147813038 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:33 INFO - 1500147813038 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813040 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813042 Marionette TRACE 379 <- [1,1,null,{"sessionId":"2a585283-86d3-4e55-91bb-9f3dbfdd8c40","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813044 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
12:43:33 INFO - 1500147813044 Marionette TRACE 379 <- [1,2,null,{}]
12:43:33 INFO - 1500147813045 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:33 INFO - 1500147813046 Marionette TRACE 379 <- [1,3,null,{}]
12:43:33 INFO - 1500147813047 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:33 INFO - 1500147813047 Marionette TRACE 379 <- [1,4,null,{}]
12:43:33 INFO - 1500147813048 Marionette TRACE 379 -> [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}]
12:43:33 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500147813050 Marionette TRACE 379 <- [1,5,null,{"value":null}]
12:43:33 INFO - 1500147813051 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
12:43:33 INFO - 1500147813052 Marionette TRACE 379 <- [1,6,null,{}]
12:43:33 INFO - 1500147813054 Marionette DEBUG Closed connection 379
12:43:33 INFO - 1500147813054 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49797
12:43:33 INFO - 1500147813055 Marionette DEBUG Closed connection 380
12:43:33 INFO - 1500147813055 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49798
12:43:33 INFO - 1500147813056 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:33 INFO - 1500147813057 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813059 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813061 Marionette TRACE 381 <- [1,1,null,{"sessionId":"4419fe56-0a26-4f8f-9e3c-3504a81717e8","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813063 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
12:43:33 INFO - 1500147813064 Marionette TRACE 381 <- [1,2,null,{}]
12:43:33 INFO - 1500147813065 Marionette DEBUG Closed connection 381
12:43:33 INFO - 1500147813066 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49799
12:43:33 INFO - 1500147813066 Marionette DEBUG Closed connection 382
12:43:33 INFO - 1500147813067 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49800
12:43:33 INFO - 1500147813068 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:33 INFO - 1500147813068 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813070 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813072 Marionette TRACE 383 <- [1,1,null,{"sessionId":"2c67be47-a37c-454e-8072-1adc14116766","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813074 Marionette TRACE 383 -> [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"}]
12:43:33 INFO - 1500147813075 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"
12:43:33 INFO - 1500147813077 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"
12:43:33 INFO - 1500147813077 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"
12:43:33 INFO - 1500147813086 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"
12:43:33 INFO - 1500147813089 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"
12:43:33 INFO - 1500147813092 Marionette TRACE 383 <- [1,2,null,{}]
12:43:33 INFO - 1500147813099 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:43:33 INFO - 1500147813102 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bc708513-11b0-4112-99cc-fe95a94a6d28","ELEMENT":"bc708513-11b0-4112-99cc-fe95a94a6d28"}}]
12:43:33 INFO - 1500147813103 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"bc708513-11b0-4112-99cc-fe95a94a6d28"}]
12:43:33 INFO - 1500147813339 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:33 INFO - 1500147813339 Marionette TRACE 383 <- [1,4,null,{}]
12:43:33 INFO - 1500147813341 Marionette TRACE 383 -> [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}]
12:43:33 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500147813343 Marionette TRACE 383 <- [1,5,null,{"value":null}]
12:43:33 INFO - 1500147813344 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
12:43:33 INFO - 1500147813345 Marionette TRACE 383 <- [1,6,null,{}]
12:43:33 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 312ms
12:43:33 INFO - TEST-START | test_click.py TestClick.test_input_file
12:43:33 INFO - 1500147813347 Marionette DEBUG Closed connection 383
12:43:33 INFO - 1500147813348 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49801
12:43:33 INFO - 1500147813348 Marionette DEBUG Closed connection 384
12:43:33 INFO - 1500147813349 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49802
12:43:33 INFO - 1500147813350 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:33 INFO - 1500147813350 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813352 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813355 Marionette TRACE 385 <- [1,1,null,{"sessionId":"cc33e53a-0c19-408f-86b1-d655cc68a975","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813356 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
12:43:33 INFO - 1500147813357 Marionette TRACE 385 <- [1,2,null,{}]
12:43:33 INFO - 1500147813357 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:33 INFO - 1500147813358 Marionette TRACE 385 <- [1,3,null,{}]
12:43:33 INFO - 1500147813359 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:33 INFO - 1500147813359 Marionette TRACE 385 <- [1,4,null,{}]
12:43:33 INFO - 1500147813361 Marionette TRACE 385 -> [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}]
12:43:33 INFO - TEST-START: test_click.py TestClick.test_input_file1500147813363 Marionette TRACE 385 <- [1,5,null,{"value":null}]
12:43:33 INFO - 1500147813363 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
12:43:33 INFO - 1500147813364 Marionette TRACE 385 <- [1,6,null,{}]
12:43:33 INFO - 1500147813366 Marionette DEBUG Closed connection 385
12:43:33 INFO - 1500147813366 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49803
12:43:33 INFO - 1500147813367 Marionette DEBUG Closed connection 386
12:43:33 INFO - 1500147813368 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49804
12:43:33 INFO - 1500147813369 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:33 INFO - 1500147813369 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813371 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813374 Marionette TRACE 387 <- [1,1,null,{"sessionId":"b4094f04-5f83-46ff-a7fc-19e9ad703ce0","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813376 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
12:43:33 INFO - 1500147813377 Marionette TRACE 387 <- [1,2,null,{}]
12:43:33 INFO - 1500147813379 Marionette DEBUG Closed connection 387
12:43:33 INFO - 1500147813379 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49805
12:43:33 INFO - 1500147813380 Marionette DEBUG Closed connection 388
12:43:33 INFO - 1500147813380 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49806
12:43:33 INFO - 1500147813381 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:33 INFO - 1500147813382 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813384 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813386 Marionette TRACE 389 <- [1,1,null,{"sessionId":"286ef5a0-1a85-4865-89a8-26e7d4bfc66c","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813388 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:43:33 INFO - 1500147813389 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"
12:43:33 INFO - 1500147813390 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"
12:43:33 INFO - 1500147813391 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"
12:43:33 INFO - 1500147813406 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:33 INFO - 1500147813409 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:33 INFO - 1500147813416 Marionette TRACE 389 <- [1,2,null,{}]
12:43:33 INFO - 1500147813418 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:43:33 INFO - 1500147813420 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"68f4720d-cf48-4e43-be08-0582584c6991","ELEMENT":"68f4720d-cf48-4e43-be08-0582584c6991"}}]
12:43:33 INFO - 1500147813421 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"68f4720d-cf48-4e43-be08-0582584c6991"}]
12:43:33 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 111ms
12:43:33 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:43:33 INFO - 1500147813451 Marionette TRACE 389 <- [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]
12:43:33 INFO - 1500147813453 Marionette TRACE 389 -> [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}]
12:43:33 INFO - TEST-END: test_click.py TestClick.test_input_file1500147813455 Marionette TRACE 389 <- [1,5,null,{"value":null}]
12:43:33 INFO - 1500147813456 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
12:43:33 INFO - 1500147813457 Marionette TRACE 389 <- [1,6,null,{}]
12:43:33 INFO - 1500147813459 Marionette DEBUG Closed connection 389
12:43:33 INFO - 1500147813460 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49807
12:43:33 INFO - 1500147813461 Marionette DEBUG Closed connection 390
12:43:33 INFO - 1500147813462 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49808
12:43:33 INFO - 1500147813462 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:33 INFO - 1500147813463 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813465 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813467 Marionette TRACE 391 <- [1,1,null,{"sessionId":"06d2bcd9-770d-4922-b690-bc95b324efde","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813469 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
12:43:33 INFO - 1500147813469 Marionette TRACE 391 <- [1,2,null,{}]
12:43:33 INFO - 1500147813470 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:33 INFO - 1500147813471 Marionette TRACE 391 <- [1,3,null,{}]
12:43:33 INFO - 1500147813471 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:33 INFO - 1500147813472 Marionette TRACE 391 <- [1,4,null,{}]
12:43:33 INFO - 1500147813473 Marionette TRACE 391 -> [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}]
12:43:33 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500147813475 Marionette TRACE 391 <- [1,5,null,{"value":null}]
12:43:33 INFO - 1500147813476 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
12:43:33 INFO - 1500147813477 Marionette TRACE 391 <- [1,6,null,{}]
12:43:33 INFO - 1500147813479 Marionette DEBUG Closed connection 391
12:43:33 INFO - 1500147813480 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49809
12:43:33 INFO - 1500147813481 Marionette DEBUG Closed connection 392
12:43:33 INFO - 1500147813481 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49810
12:43:33 INFO - 1500147813482 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:33 INFO - 1500147813482 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813484 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813487 Marionette TRACE 393 <- [1,1,null,{"sessionId":"1acdd596-e50c-4b53-afe1-696152b63722","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813488 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
12:43:33 INFO - 1500147813489 Marionette TRACE 393 <- [1,2,null,{}]
12:43:33 INFO - 1500147813491 Marionette DEBUG Closed connection 393
12:43:33 INFO - 1500147813491 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49811
12:43:33 INFO - 1500147813492 Marionette DEBUG Closed connection 394
12:43:33 INFO - 1500147813493 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49812
12:43:33 INFO - 1500147813493 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:33 INFO - 1500147813494 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813496 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813498 Marionette TRACE 395 <- [1,1,null,{"sessionId":"16c6116c-4faa-4dec-b012-b7746e64b6c5","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813500 Marionette TRACE 395 -> [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"}]
12:43:33 INFO - 1500147813501 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:33 INFO - 1500147813503 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:33 INFO - 1500147813503 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:43:33 INFO - 1500147813518 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"
12:43:33 INFO - 1500147813521 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"
12:43:33 INFO - 1500147813523 Marionette TRACE 395 <- [1,2,null,{}]
12:43:33 INFO - 1500147813524 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:43:33 INFO - 1500147813531 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c138ce7e-d905-4ad6-8f0e-91e577000fa9","ELEMENT":"c138ce7e-d905-4ad6-8f0e-91e577000fa9"}}]
12:43:33 INFO - 1500147813532 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:43:33 INFO - 1500147813533 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da61b452-ae03-4f2b-af93-21404213ede7","ELEMENT":"da61b452-ae03-4f2b-af93-21404213ede7"}}]
12:43:33 INFO - 1500147813534 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"c138ce7e-d905-4ad6-8f0e-91e577000fa9"}]
12:43:33 INFO - 1500147813769 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:43:33 INFO - 1500147813769 Marionette TRACE 395 <- [1,5,null,{}]
12:43:33 INFO - 1500147813770 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"da61b452-ae03-4f2b-af93-21404213ede7"}]
12:43:33 INFO - 1500147813783 Marionette TRACE 395 <- [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]
12:43:33 INFO - 1500147813785 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:43:33 INFO - 1500147813787 Marionette TRACE 395 <- [1,7,null,{"value":false}]
12:43:33 INFO - 1500147813788 Marionette TRACE 395 -> [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}]
12:43:33 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500147813790 Marionette TRACE 395 <- [1,8,null,{"value":null}]
12:43:33 INFO - 1500147813792 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
12:43:33 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 335ms
12:43:33 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:43:33 INFO - 1500147813793 Marionette TRACE 395 <- [1,9,null,{}]
12:43:33 INFO - 1500147813794 Marionette DEBUG Closed connection 395
12:43:33 INFO - 1500147813795 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49813
12:43:33 INFO - 1500147813796 Marionette DEBUG Closed connection 396
12:43:33 INFO - 1500147813797 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49814
12:43:33 INFO - 1500147813798 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:33 INFO - 1500147813798 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813800 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813809 Marionette TRACE 397 <- [1,1,null,{"sessionId":"2ad1ef91-74a0-4edb-9d85-fa45cd911648","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813811 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
12:43:33 INFO - 1500147813811 Marionette TRACE 397 <- [1,2,null,{}]
12:43:33 INFO - 1500147813812 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:43:33 INFO - 1500147813812 Marionette TRACE 397 <- [1,3,null,{}]
12:43:33 INFO - 1500147813813 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
12:43:33 INFO - 1500147813814 Marionette TRACE 397 <- [1,4,null,{}]
12:43:33 INFO - 1500147813815 Marionette TRACE 397 -> [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}]
12:43:33 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500147813817 Marionette TRACE 397 <- [1,5,null,{"value":null}]
12:43:33 INFO - 1500147813818 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
12:43:33 INFO - 1500147813819 Marionette TRACE 397 <- [1,6,null,{}]
12:43:33 INFO - 1500147813821 Marionette DEBUG Closed connection 397
12:43:33 INFO - 1500147813821 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49815
12:43:33 INFO - 1500147813822 Marionette DEBUG Closed connection 398
12:43:33 INFO - 1500147813822 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49816
12:43:33 INFO - 1500147813823 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:43:33 INFO - 1500147813824 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813826 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813828 Marionette TRACE 399 <- [1,1,null,{"sessionId":"507dd8a0-2fda-4608-83ff-f69b6de26c0c","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813830 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
12:43:33 INFO - 1500147813831 Marionette TRACE 399 <- [1,2,null,{}]
12:43:33 INFO - 1500147813833 Marionette DEBUG Closed connection 399
12:43:33 INFO - 1500147813833 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49817
12:43:33 INFO - 1500147813834 Marionette DEBUG Closed connection 400
12:43:33 INFO - 1500147813834 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49818
12:43:33 INFO - 1500147813835 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:43:33 INFO - 1500147813836 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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}
12:43:33 INFO - 1500147813838 Marionette DEBUG Register listener.js for window 15
12:43:33 INFO - 1500147813840 Marionette TRACE 401 <- [1,1,null,{"sessionId":"c1347c80-aa80-4e13-b926-c8a7641c1dee","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":2224,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-296\\AppData\\Local\\Temp\\tmpyisqkd.mozrunner","moz:accessibilityChecks":false}}]
12:43:33 INFO - 1500147813842 Marionette TRACE 401 -> [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"}]
12:43:33 INFO - 1500147813843 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"
12:43:33 INFO - 1500147813845 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"
12:43:33 INFO - 1500147813845 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"
12:43:33 INFO - 1500147813855 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"
12:43:33 INFO - 1500147813858 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"
12:43:33 INFO - 1500147813866 Marionette TRACE 401 <- [1,2,null,{}]
12:43:33 INFO - 1500147813868 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:43:33 INFO - 1500147813869 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1c8849fd-65e0-4338-bcd4-5d664e829266","ELEMENT":"1c8849fd-65e0-4338-bcd4-5d664e829266"}}]
12:43:33 INFO - 1500147813870 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1c8849fd-65e0-4338-bcd4-5d664e829266"}]
12:43:33 INFO - 1500147813892 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
12:43:33 INFO - 1500147813893 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"1c8849fd-65e0-4338-bcd4-5d664e829266"}]
12:43:33 INFO - 1500147813906 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element