could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
20:26:49 INFO - 1498879609816 Marionette TRACE 358 -> [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}]
20:26:49 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498879609827 Marionette TRACE 358 <- [1,5,null,{"value":null}]
20:26:49 INFO - 1498879609828 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
20:26:49 INFO - 1498879609829 Marionette TRACE 358 <- [1,6,null,{}]
20:26:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 131ms
20:26:49 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
20:26:49 INFO - 1498879609831 Marionette DEBUG Closed connection 358
20:26:49 INFO - 1498879609832 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50059
20:26:49 INFO - 1498879609832 Marionette DEBUG Closed connection 359
20:26:49 INFO - 1498879609833 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50060
20:26:49 INFO - 1498879609834 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:49 INFO - 1498879609835 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:49 INFO - 1498879609840 Marionette DEBUG Register listener.js for window 2147483649
20:26:49 INFO - 1498879609843 Marionette TRACE 360 <- [1,1,null,{"sessionId":"dd4d3165-7409-4633-a7c4-65c16ab9cd8e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:49 INFO - 1498879609844 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
20:26:49 INFO - 1498879609844 Marionette TRACE 360 <- [1,2,null,{}]
20:26:49 INFO - 1498879609845 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:26:49 INFO - 1498879609845 Marionette TRACE 360 <- [1,3,null,{}]
20:26:49 INFO - 1498879609846 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
20:26:49 INFO - 1498879609847 Marionette TRACE 360 <- [1,4,null,{}]
20:26:49 INFO - 1498879609849 Marionette TRACE 360 -> [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}]
20:26:49 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498879609851 Marionette TRACE 360 <- [1,5,null,{"value":null}]
20:26:49 INFO - 1498879609852 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
20:26:49 INFO - 1498879609853 Marionette TRACE 360 <- [1,6,null,{}]
20:26:49 INFO - 1498879609854 Marionette DEBUG Closed connection 360
20:26:49 INFO - 1498879609855 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50061
20:26:49 INFO - 1498879609856 Marionette DEBUG Closed connection 361
20:26:49 INFO - 1498879609857 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50062
20:26:49 INFO - 1498879609858 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:49 INFO - 1498879609858 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:49 INFO - 1498879609860 Marionette DEBUG Register listener.js for window 2147483649
20:26:49 INFO - 1498879609863 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3935c3a5-117f-48c4-8efe-5f9fe398c2a5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:49 INFO - 1498879609864 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
20:26:49 INFO - 1498879609865 Marionette TRACE 362 <- [1,2,null,{}]
20:26:49 INFO - 1498879609866 Marionette DEBUG Closed connection 362
20:26:49 INFO - 1498879609867 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50063
20:26:49 INFO - 1498879609867 Marionette DEBUG Closed connection 363
20:26:49 INFO - 1498879609868 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50064
20:26:49 INFO - 1498879609869 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:26:49 INFO - 1498879609870 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:49 INFO - 1498879609871 Marionette DEBUG Register listener.js for window 2147483649
20:26:49 INFO - 1498879609874 Marionette TRACE 364 <- [1,1,null,{"sessionId":"7b691e98-8066-4304-bed0-dbc983828e5f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:49 INFO - 1498879609875 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49662/clicks.html"}]
20:26:49 INFO - 1498879609877 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49662/hidden.html"
20:26:49 INFO - 1498879609884 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49662/hidden.html"
20:26:49 INFO - 1498879609884 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49662/hidden.html"
20:26:49 INFO - 1498879609894 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49662/clicks.html"
20:26:49 INFO - 1498879609896 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49662/clicks.html"
20:26:49 INFO - 1498879609899 Marionette TRACE 364 <- [1,2,null,{}]
20:26:49 INFO - 1498879609903 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
20:26:49 INFO - 1498879609910 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"acd1ceb5-b921-496d-9740-e2d88bc5ea9a","ELEMENT":"acd1ceb5-b921-496d-9740-e2d88bc5ea9a"}}]
20:26:49 INFO - 1498879609911 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"acd1ceb5-b921-496d-9740-e2d88bc5ea9a"}]
20:26:49 INFO - 1498879609951 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49662/clicks.html"
20:26:49 INFO - 1498879609972 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49662/clicks.html"
20:26:49 INFO - 1498879609972 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49662/clicks.html"
20:26:49 INFO - 1498879609981 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49662/test.html"
20:26:49 INFO - 1498879609986 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49662/test.html"
20:26:49 INFO - 1498879609987 Marionette TRACE 364 <- [1,4,null,{}]
20:26:49 INFO - 1498879609993 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
20:26:50 INFO - 1498879609998 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7fc1fc66-fe8b-401b-8b19-d0c53bac1392","ELEMENT":"7fc1fc66-fe8b-401b-8b19-d0c53bac1392"}}]
20:26:50 INFO - 1498879610000 Marionette TRACE 364 -> [0,6,"getTitle",{}]
20:26:50 INFO - 1498879610002 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
20:26:50 INFO - 1498879610004 Marionette TRACE 364 -> [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}]
20:26:50 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498879610007 Marionette TRACE 364 <- [1,7,null,{"value":null}]
20:26:50 INFO - 1498879610008 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
20:26:50 INFO - 1498879610009 Marionette TRACE 364 <- [1,8,null,{}]
20:26:50 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 181ms
20:26:50 INFO - TEST-START | test_click.py TestClick.test_container_element
20:26:50 INFO - 1498879610011 Marionette DEBUG Closed connection 364
20:26:50 INFO - 1498879610012 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50067
20:26:50 INFO - 1498879610013 Marionette DEBUG Closed connection 365
20:26:50 INFO - 1498879610014 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50068
20:26:50 INFO - 1498879610015 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:50 INFO - 1498879610015 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610022 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610026 Marionette TRACE 366 <- [1,1,null,{"sessionId":"f726f3ee-6b85-48ee-b6f6-f1b9c57ae4ee","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610027 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
20:26:50 INFO - 1498879610027 Marionette TRACE 366 <- [1,2,null,{}]
20:26:50 INFO - 1498879610028 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:26:50 INFO - 1498879610028 Marionette TRACE 366 <- [1,3,null,{}]
20:26:50 INFO - 1498879610029 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
20:26:50 INFO - 1498879610030 Marionette TRACE 366 <- [1,4,null,{}]
20:26:50 INFO - 1498879610031 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')","sandbox":"simpletest","line":316}]
20:26:50 INFO - TEST-START: test_click.py TestClick.test_container_element1498879610034 Marionette TRACE 366 <- [1,5,null,{"value":null}]
20:26:50 INFO - 1498879610035 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
20:26:50 INFO - 1498879610036 Marionette TRACE 366 <- [1,6,null,{}]
20:26:50 INFO - 1498879610038 Marionette DEBUG Closed connection 366
20:26:50 INFO - 1498879610038 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50069
20:26:50 INFO - 1498879610039 Marionette DEBUG Closed connection 367
20:26:50 INFO - 1498879610040 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50070
20:26:50 INFO - 1498879610041 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:50 INFO - 1498879610041 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610043 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610046 Marionette TRACE 368 <- [1,1,null,{"sessionId":"2a9ca424-feaf-438b-a27a-dd3f3b8cb29e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610047 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
20:26:50 INFO - 1498879610048 Marionette TRACE 368 <- [1,2,null,{}]
20:26:50 INFO - 1498879610050 Marionette DEBUG Closed connection 368
20:26:50 INFO - 1498879610050 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50071
20:26:50 INFO - 1498879610051 Marionette DEBUG Closed connection 369
20:26:50 INFO - 1498879610052 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50072
20:26:50 INFO - 1498879610052 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:26:50 INFO - 1498879610053 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610055 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610057 Marionette TRACE 370 <- [1,1,null,{"sessionId":"eb02a91e-84d1-4290-9b36-156906e59de5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610058 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%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"}]
20:26:50 INFO - 1498879610060 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49662/test.html"
20:26:50 INFO - 1498879610062 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49662/test.html"
20:26:50 INFO - 1498879610062 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49662/test.html"
20:26:50 INFO - 1498879610068 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"
20:26:50 INFO - 1498879610071 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"
20:26:50 INFO - 1498879610081 Marionette TRACE 370 <- [1,2,null,{}]
20:26:50 INFO - 1498879610086 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:26:50 INFO - 1498879610088 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9013ec0d-26a0-4e37-883e-254a9db6479b","ELEMENT":"9013ec0d-26a0-4e37-883e-254a9db6479b"}}]
20:26:50 INFO - 1498879610093 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9013ec0d-26a0-4e37-883e-254a9db6479b"}]
20:26:50 INFO - 1498879610329 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:26:50 INFO - 1498879610330 Marionette TRACE 370 <- [1,4,null,{}]
20:26:50 INFO - 1498879610331 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9013ec0d-26a0-4e37-883e-254a9db6479b","name":"selected"}]
20:26:50 INFO - 1498879610335 Marionette TRACE 370 <- [1,5,null,{"value":true}]
20:26:50 INFO - 1498879610337 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')","sandbox":"simpletest","line":328}]
20:26:50 INFO - TEST-END: test_click.py TestClick.test_container_element1498879610339 Marionette TRACE 370 <- [1,6,null,{"value":null}]
20:26:50 INFO - 1498879610340 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
20:26:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 331ms
20:26:50 INFO - 1498879610342 Marionette TRACE 370 <- [1,7,null,{}]
20:26:50 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
20:26:50 INFO - 1498879610343 Marionette DEBUG Closed connection 370
20:26:50 INFO - 1498879610344 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50073
20:26:50 INFO - 1498879610345 Marionette DEBUG Closed connection 371
20:26:50 INFO - 1498879610346 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50074
20:26:50 INFO - 1498879610346 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:50 INFO - 1498879610347 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610349 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610352 Marionette TRACE 372 <- [1,1,null,{"sessionId":"a135c068-0d72-4f8c-b733-813536551f8d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610353 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
20:26:50 INFO - 1498879610353 Marionette TRACE 372 <- [1,2,null,{}]
20:26:50 INFO - 1498879610354 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:26:50 INFO - 1498879610354 Marionette TRACE 372 <- [1,3,null,{}]
20:26:50 INFO - 1498879610355 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
20:26:50 INFO - 1498879610356 Marionette TRACE 372 <- [1,4,null,{}]
20:26:50 INFO - 1498879610357 Marionette TRACE 372 -> [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}]
20:26:50 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498879610360 Marionette TRACE 372 <- [1,5,null,{"value":null}]
20:26:50 INFO - 1498879610361 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
20:26:50 INFO - 1498879610362 Marionette TRACE 372 <- [1,6,null,{}]
20:26:50 INFO - 1498879610363 Marionette DEBUG Closed connection 372
20:26:50 INFO - 1498879610364 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50075
20:26:50 INFO - 1498879610364 Marionette DEBUG Closed connection 373
20:26:50 INFO - 1498879610365 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50076
20:26:50 INFO - 1498879610366 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:50 INFO - 1498879610367 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610368 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610371 Marionette TRACE 374 <- [1,1,null,{"sessionId":"3645188f-b246-4c76-8b56-538eb2c8e186","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610372 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
20:26:50 INFO - 1498879610373 Marionette TRACE 374 <- [1,2,null,{}]
20:26:50 INFO - 1498879610375 Marionette DEBUG Closed connection 374
20:26:50 INFO - 1498879610375 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50077
20:26:50 INFO - 1498879610376 Marionette DEBUG Closed connection 375
20:26:50 INFO - 1498879610377 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50078
20:26:50 INFO - 1498879610378 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:26:50 INFO - 1498879610378 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610380 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610383 Marionette TRACE 376 <- [1,1,null,{"sessionId":"b774c218-9a80-481b-96ec-b600155286f9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610384 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%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"}]
20:26:50 INFO - 1498879610385 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"
20:26:50 INFO - 1498879610387 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"
20:26:50 INFO - 1498879610387 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"
20:26:50 INFO - 1498879610393 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"
20:26:50 INFO - 1498879610396 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"
20:26:50 INFO - 1498879610399 Marionette TRACE 376 <- [1,2,null,{}]
20:26:50 INFO - 1498879610402 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:26:50 INFO - 1498879610404 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e720f874-ab3e-44e9-977d-c53284bc0de7","ELEMENT":"e720f874-ab3e-44e9-977d-c53284bc0de7"}}]
20:26:50 INFO - 1498879610405 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e720f874-ab3e-44e9-977d-c53284bc0de7"}]
20:26:50 INFO - 1498879610647 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:26:50 INFO - 1498879610648 Marionette TRACE 376 <- [1,4,null,{}]
20:26:50 INFO - 1498879610649 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"e720f874-ab3e-44e9-977d-c53284bc0de7","name":"selected"}]
20:26:50 INFO - 1498879610654 Marionette TRACE 376 <- [1,5,null,{"value":true}]
20:26:50 INFO - 1498879610655 Marionette TRACE 376 -> [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}]
20:26:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498879610658 Marionette TRACE 376 <- [1,6,null,{"value":null}]
20:26:50 INFO - 1498879610659 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
20:26:50 INFO - 1498879610660 Marionette TRACE 376 <- [1,7,null,{}]
20:26:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 317ms
20:26:50 INFO - 1498879610661 Marionette DEBUG Closed connection 376
20:26:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
20:26:50 INFO - 1498879610663 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50079
20:26:50 INFO - 1498879610664 Marionette DEBUG Closed connection 377
20:26:50 INFO - 1498879610665 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50080
20:26:50 INFO - 1498879610666 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:50 INFO - 1498879610666 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610668 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610671 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6ed27c71-536f-4754-b2c9-149f1c1dbf70","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610672 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
20:26:50 INFO - 1498879610672 Marionette TRACE 378 <- [1,2,null,{}]
20:26:50 INFO - 1498879610673 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:26:50 INFO - 1498879610674 Marionette TRACE 378 <- [1,3,null,{}]
20:26:50 INFO - 1498879610674 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
20:26:50 INFO - 1498879610675 Marionette TRACE 378 <- [1,4,null,{}]
20:26:50 INFO - 1498879610676 Marionette TRACE 378 -> [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}]
20:26:50 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498879610679 Marionette TRACE 378 <- [1,5,null,{"value":null}]
20:26:50 INFO - 1498879610680 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
20:26:50 INFO - 1498879610681 Marionette TRACE 378 <- [1,6,null,{}]
20:26:50 INFO - 1498879610682 Marionette DEBUG Closed connection 378
20:26:50 INFO - 1498879610683 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50081
20:26:50 INFO - 1498879610683 Marionette DEBUG Closed connection 379
20:26:50 INFO - 1498879610684 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50082
20:26:50 INFO - 1498879610685 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:50 INFO - 1498879610686 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610688 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610691 Marionette TRACE 380 <- [1,1,null,{"sessionId":"61be12a5-1831-4d77-829a-f20968fd9d9d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610692 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
20:26:50 INFO - 1498879610693 Marionette TRACE 380 <- [1,2,null,{}]
20:26:50 INFO - 1498879610694 Marionette DEBUG Closed connection 380
20:26:50 INFO - 1498879610695 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50083
20:26:50 INFO - 1498879610696 Marionette DEBUG Closed connection 381
20:26:50 INFO - 1498879610696 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50084
20:26:50 INFO - 1498879610697 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:26:50 INFO - 1498879610698 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:50 INFO - 1498879610700 Marionette DEBUG Register listener.js for window 2147483649
20:26:50 INFO - 1498879610702 Marionette TRACE 382 <- [1,1,null,{"sessionId":"12ebb3b4-ffc1-4b26-a375-53cde24b0101","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:50 INFO - 1498879610703 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%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"}]
20:26:50 INFO - 1498879610705 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"
20:26:50 INFO - 1498879610707 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"
20:26:50 INFO - 1498879610707 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"
20:26:50 INFO - 1498879610713 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"
20:26:50 INFO - 1498879610715 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"
20:26:50 INFO - 1498879610717 Marionette TRACE 382 <- [1,2,null,{}]
20:26:50 INFO - 1498879610720 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
20:26:50 INFO - 1498879610722 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"716b2767-7dd0-4099-8582-2a19650a4aca","ELEMENT":"716b2767-7dd0-4099-8582-2a19650a4aca"}}]
20:26:50 INFO - 1498879610729 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"716b2767-7dd0-4099-8582-2a19650a4aca"}]
20:26:50 INFO - 1498879610963 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:26:50 INFO - 1498879610968 Marionette TRACE 382 <- [1,4,null,{}]
20:26:50 INFO - 1498879610972 Marionette TRACE 382 -> [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}]
20:26:50 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498879610976 Marionette TRACE 382 <- [1,5,null,{"value":null}]
20:26:50 INFO - 1498879610978 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
20:26:50 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 320ms
20:26:50 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
20:26:50 INFO - 1498879610980 Marionette TRACE 382 <- [1,6,null,{}]
20:26:50 INFO - 1498879610983 Marionette DEBUG Closed connection 382
20:26:50 INFO - 1498879610984 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50085
20:26:51 INFO - 1498879611010 Marionette DEBUG Closed connection 383
20:26:51 INFO - 1498879611011 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50086
20:26:51 INFO - 1498879611018 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:51 INFO - 1498879611020 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611023 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611038 Marionette TRACE 384 <- [1,1,null,{"sessionId":"2c7380b5-e087-493a-a2f3-e8c78f027a89","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611040 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
20:26:51 INFO - 1498879611041 Marionette TRACE 384 <- [1,2,null,{}]
20:26:51 INFO - 1498879611042 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:26:51 INFO - 1498879611043 Marionette TRACE 384 <- [1,3,null,{}]
20:26:51 INFO - 1498879611044 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
20:26:51 INFO - 1498879611045 Marionette TRACE 384 <- [1,4,null,{}]
20:26:51 INFO - 1498879611053 Marionette TRACE 384 -> [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}]
20:26:51 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498879611059 Marionette TRACE 384 <- [1,5,null,{"value":null}]
20:26:51 INFO - 1498879611061 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
20:26:51 INFO - 1498879611063 Marionette TRACE 384 <- [1,6,null,{}]
20:26:51 INFO - 1498879611065 Marionette DEBUG Closed connection 384
20:26:51 INFO - 1498879611066 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50087
20:26:51 INFO - 1498879611067 Marionette DEBUG Closed connection 385
20:26:51 INFO - 1498879611068 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50088
20:26:51 INFO - 1498879611069 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:51 INFO - 1498879611070 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611072 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611076 Marionette TRACE 386 <- [1,1,null,{"sessionId":"0b481b06-08b7-4c1f-a338-e87d62372dd3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611078 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
20:26:51 INFO - 1498879611079 Marionette TRACE 386 <- [1,2,null,{}]
20:26:51 INFO - 1498879611081 Marionette DEBUG Closed connection 386
20:26:51 INFO - 1498879611082 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50089
20:26:51 INFO - 1498879611083 Marionette DEBUG Closed connection 387
20:26:51 INFO - 1498879611084 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50090
20:26:51 INFO - 1498879611086 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:26:51 INFO - 1498879611088 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611090 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611093 Marionette TRACE 388 <- [1,1,null,{"sessionId":"be6b32e4-c3a6-452e-9b2c-43800cb0b228","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611094 Marionette TRACE 388 -> [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"}]
20:26:51 INFO - 1498879611097 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"
20:26:51 INFO - 1498879611098 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"
20:26:51 INFO - 1498879611099 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"
20:26:51 INFO - 1498879611104 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"
20:26:51 INFO - 1498879611107 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"
20:26:51 INFO - 1498879611119 Marionette TRACE 388 <- [1,2,null,{}]
20:26:51 INFO - 1498879611129 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
20:26:51 INFO - 1498879611132 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21c26f4c-359d-46c4-b72f-1f1ed718c065","ELEMENT":"21c26f4c-359d-46c4-b72f-1f1ed718c065"}}]
20:26:51 INFO - 1498879611133 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"21c26f4c-359d-46c4-b72f-1f1ed718c065"}]
20:26:51 INFO - 1498879611379 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:26:51 INFO - 1498879611381 Marionette TRACE 388 <- [1,4,null,{}]
20:26:51 INFO - 1498879611382 Marionette TRACE 388 -> [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}]
20:26:51 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498879611385 Marionette TRACE 388 <- [1,5,null,{"value":null}]
20:26:51 INFO - 1498879611386 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
20:26:51 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 406ms
20:26:51 INFO - 1498879611388 Marionette TRACE 388 <- [1,6,null,{}]
20:26:51 INFO - TEST-START | test_click.py TestClick.test_input_file
20:26:51 INFO - 1498879611389 Marionette DEBUG Closed connection 388
20:26:51 INFO - 1498879611390 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50091
20:26:51 INFO - 1498879611391 Marionette DEBUG Closed connection 389
20:26:51 INFO - 1498879611392 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50092
20:26:51 INFO - 1498879611393 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:51 INFO - 1498879611393 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611396 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611399 Marionette TRACE 390 <- [1,1,null,{"sessionId":"526c70d7-5f47-4436-bbfc-cf513b70b9b0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611400 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
20:26:51 INFO - 1498879611401 Marionette TRACE 390 <- [1,2,null,{}]
20:26:51 INFO - 1498879611402 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:26:51 INFO - 1498879611403 Marionette TRACE 390 <- [1,3,null,{}]
20:26:51 INFO - 1498879611404 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
20:26:51 INFO - 1498879611404 Marionette TRACE 390 <- [1,4,null,{}]
20:26:51 INFO - 1498879611406 Marionette TRACE 390 -> [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}]
20:26:51 INFO - TEST-START: test_click.py TestClick.test_input_file1498879611409 Marionette TRACE 390 <- [1,5,null,{"value":null}]
20:26:51 INFO - 1498879611410 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
20:26:51 INFO - 1498879611411 Marionette TRACE 390 <- [1,6,null,{}]
20:26:51 INFO - 1498879611412 Marionette DEBUG Closed connection 390
20:26:51 INFO - 1498879611413 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50093
20:26:51 INFO - 1498879611414 Marionette DEBUG Closed connection 391
20:26:51 INFO - 1498879611415 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50094
20:26:51 INFO - 1498879611416 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:51 INFO - 1498879611417 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611419 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611422 Marionette TRACE 392 <- [1,1,null,{"sessionId":"2c8d5485-414c-4ea3-a4fd-d9457091d080","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611423 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
20:26:51 INFO - 1498879611425 Marionette TRACE 392 <- [1,2,null,{}]
20:26:51 INFO - 1498879611427 Marionette DEBUG Closed connection 392
20:26:51 INFO - 1498879611429 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50095
20:26:51 INFO - 1498879611430 Marionette DEBUG Closed connection 393
20:26:51 INFO - 1498879611431 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50096
20:26:51 INFO - 1498879611432 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:26:51 INFO - 1498879611433 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611436 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611439 Marionette TRACE 394 <- [1,1,null,{"sessionId":"704f9089-0a08-452e-84a3-7905ee1fd90d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611441 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
20:26:51 INFO - 1498879611443 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"
20:26:51 INFO - 1498879611444 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"
20:26:51 INFO - 1498879611445 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"
20:26:51 INFO - 1498879611450 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:26:51 INFO - 1498879611454 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:26:51 INFO - 1498879611471 Marionette TRACE 394 <- [1,2,null,{}]
20:26:51 INFO - 1498879611479 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
20:26:51 INFO - 1498879611482 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d2756e16-1ce7-4b1d-897a-6b62cfaa8c13","ELEMENT":"d2756e16-1ce7-4b1d-897a-6b62cfaa8c13"}}]
20:26:51 INFO - 1498879611483 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"d2756e16-1ce7-4b1d-897a-6b62cfaa8c13"}]
20:26:51 INFO - 1498879611511 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
20:26:51 INFO - 1498879611513 Marionette TRACE 394 -> [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}]
20:26:51 INFO - TEST-END: test_click.py TestClick.test_input_file1498879611516 Marionette TRACE 394 <- [1,5,null,{"value":null}]
20:26:51 INFO - 1498879611517 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
20:26:51 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 130ms
20:26:51 INFO - 1498879611519 Marionette TRACE 394 <- [1,6,null,{}]
20:26:51 INFO - TEST-START | test_click.py TestClick.test_obscured_element
20:26:51 INFO - 1498879611520 Marionette DEBUG Closed connection 394
20:26:51 INFO - 1498879611521 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50097
20:26:51 INFO - 1498879611522 Marionette DEBUG Closed connection 395
20:26:51 INFO - 1498879611523 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50098
20:26:51 INFO - 1498879611524 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:51 INFO - 1498879611524 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611527 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611529 Marionette TRACE 396 <- [1,1,null,{"sessionId":"78950711-e98d-4c67-bade-1ab3d526a755","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611530 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
20:26:51 INFO - 1498879611531 Marionette TRACE 396 <- [1,2,null,{}]
20:26:51 INFO - 1498879611532 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:26:51 INFO - 1498879611532 Marionette TRACE 396 <- [1,3,null,{}]
20:26:51 INFO - 1498879611533 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
20:26:51 INFO - 1498879611534 Marionette TRACE 396 <- [1,4,null,{}]
20:26:51 INFO - 1498879611535 Marionette TRACE 396 -> [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}]
20:26:51 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498879611538 Marionette TRACE 396 <- [1,5,null,{"value":null}]
20:26:51 INFO - 1498879611539 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
20:26:51 INFO - 1498879611540 Marionette TRACE 396 <- [1,6,null,{}]
20:26:51 INFO - 1498879611542 Marionette DEBUG Closed connection 396
20:26:51 INFO - 1498879611542 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50099
20:26:51 INFO - 1498879611543 Marionette DEBUG Closed connection 397
20:26:51 INFO - 1498879611544 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50100
20:26:51 INFO - 1498879611545 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:51 INFO - 1498879611545 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611547 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611550 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4ebfff43-0f2f-4556-9c51-d8823df15398","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611551 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
20:26:51 INFO - 1498879611552 Marionette TRACE 398 <- [1,2,null,{}]
20:26:51 INFO - 1498879611554 Marionette DEBUG Closed connection 398
20:26:51 INFO - 1498879611554 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50101
20:26:51 INFO - 1498879611555 Marionette DEBUG Closed connection 399
20:26:51 INFO - 1498879611556 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50102
20:26:51 INFO - 1498879611557 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:26:51 INFO - 1498879611557 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611559 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611562 Marionette TRACE 400 <- [1,1,null,{"sessionId":"72d9eb80-8057-4b20-9671-af71bcbe12ae","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611563 Marionette TRACE 400 -> [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"}]
20:26:51 INFO - 1498879611565 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:26:51 INFO - 1498879611567 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:26:51 INFO - 1498879611567 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:26:51 INFO - 1498879611573 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"
20:26:51 INFO - 1498879611575 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"
20:26:51 INFO - 1498879611584 Marionette TRACE 400 <- [1,2,null,{}]
20:26:51 INFO - 1498879611589 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
20:26:51 INFO - 1498879611631 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ced31631-5aac-4a95-9601-541e8df77b25","ELEMENT":"ced31631-5aac-4a95-9601-541e8df77b25"}}]
20:26:51 INFO - 1498879611632 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
20:26:51 INFO - 1498879611635 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c9f0f1d1-f9fe-433e-a793-979c5e62a2a9","ELEMENT":"c9f0f1d1-f9fe-433e-a793-979c5e62a2a9"}}]
20:26:51 INFO - 1498879611635 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"ced31631-5aac-4a95-9601-541e8df77b25"}]
20:26:51 INFO - 1498879611884 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:26:51 INFO - 1498879611885 Marionette TRACE 400 <- [1,5,null,{}]
20:26:51 INFO - 1498879611886 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"c9f0f1d1-f9fe-433e-a793-979c5e62a2a9"}]
20:26:51 INFO - 1498879611902 Marionette TRACE 400 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
20:26:51 INFO - 1498879611903 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
20:26:51 INFO - 1498879611907 Marionette TRACE 400 <- [1,7,null,{"value":false}]
20:26:51 INFO - 1498879611908 Marionette TRACE 400 -> [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}]
20:26:51 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498879611911 Marionette TRACE 400 <- [1,8,null,{"value":null}]
20:26:51 INFO - 1498879611912 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
20:26:51 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 394ms
20:26:51 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
20:26:51 INFO - 1498879611913 Marionette TRACE 400 <- [1,9,null,{}]
20:26:51 INFO - 1498879611915 Marionette DEBUG Closed connection 400
20:26:51 INFO - 1498879611916 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50103
20:26:51 INFO - 1498879611916 Marionette DEBUG Closed connection 401
20:26:51 INFO - 1498879611917 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50104
20:26:51 INFO - 1498879611918 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:51 INFO - 1498879611919 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611921 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611926 Marionette TRACE 402 <- [1,1,null,{"sessionId":"84b24400-5134-4fe4-9984-f0c9000dd25e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611927 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
20:26:51 INFO - 1498879611927 Marionette TRACE 402 <- [1,2,null,{}]
20:26:51 INFO - 1498879611928 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:26:51 INFO - 1498879611929 Marionette TRACE 402 <- [1,3,null,{}]
20:26:51 INFO - 1498879611930 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
20:26:51 INFO - 1498879611930 Marionette TRACE 402 <- [1,4,null,{}]
20:26:51 INFO - 1498879611932 Marionette TRACE 402 -> [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}]
20:26:51 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498879611940 Marionette TRACE 402 <- [1,5,null,{"value":null}]
20:26:51 INFO - 1498879611940 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
20:26:51 INFO - 1498879611942 Marionette TRACE 402 <- [1,6,null,{}]
20:26:51 INFO - 1498879611943 Marionette DEBUG Closed connection 402
20:26:51 INFO - 1498879611944 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50105
20:26:51 INFO - 1498879611945 Marionette DEBUG Closed connection 403
20:26:51 INFO - 1498879611946 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50106
20:26:51 INFO - 1498879611947 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:26:51 INFO - 1498879611948 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611952 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611956 Marionette TRACE 404 <- [1,1,null,{"sessionId":"80dde4e2-b6ef-4958-bdcc-56fd64b02190","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611957 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
20:26:51 INFO - 1498879611958 Marionette TRACE 404 <- [1,2,null,{}]
20:26:51 INFO - 1498879611960 Marionette DEBUG Closed connection 404
20:26:51 INFO - 1498879611961 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50107
20:26:51 INFO - 1498879611962 Marionette DEBUG Closed connection 405
20:26:51 INFO - 1498879611962 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50108
20:26:51 INFO - 1498879611963 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:26:51 INFO - 1498879611964 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}
20:26:51 INFO - 1498879611966 Marionette DEBUG Register listener.js for window 2147483649
20:26:51 INFO - 1498879611969 Marionette TRACE 406 <- [1,1,null,{"sessionId":"26ec7c48-a3c6-4d09-9e22-84e1e85ea70a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":4208,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpgc5m4y.mozrunner","moz:accessibilityChecks":false}}]
20:26:51 INFO - 1498879611970 Marionette TRACE 406 -> [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"}]
20:26:51 INFO - 1498879611972 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"
20:26:51 INFO - 1498879611975 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"
20:26:51 INFO - 1498879611975 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"
20:26:51 INFO - 1498879611982 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"
20:26:51 INFO - 1498879611985 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"
20:26:51 INFO - 1498879611987 Marionette TRACE 406 <- [1,2,null,{}]
20:26:51 INFO - 1498879611995 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
20:26:52 INFO - 1498879612014 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0a58f919-2d55-4966-a0ae-efb21d131b9d","ELEMENT":"0a58f919-2d55-4966-a0ae-efb21d131b9d"}}]
20:26:52 INFO - 1498879612016 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"0a58f919-2d55-4966-a0ae-efb21d131b9d"}]
20:26:52 INFO - 1498879612036 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
20:26:52 INFO - 1498879612037 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"0a58f919-2d55-4966-a0ae-efb21d131b9d"}]
20:26:52 INFO - 1498879612052 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element