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:1318:5\n"},null]
17:51:35 INFO - 1499302295909 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
17:51:35 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499302295912 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:51:35 INFO - 1499302295914 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:51:35 INFO - 1499302295916 Marionette TRACE 352 <- [1,6,null,{}]
17:51:35 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 189ms
17:51:35 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:51:35 INFO - 1499302295919 Marionette DEBUG Closed connection 352
17:51:35 INFO - 1499302295920 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49840
17:51:35 INFO - 1499302295921 Marionette DEBUG Closed connection 353
17:51:35 INFO - 1499302295922 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49841
17:51:35 INFO - 1499302295924 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:35 INFO - 1499302295924 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:35 INFO - 1499302295927 Marionette DEBUG Register listener.js for window 12
17:51:35 INFO - 1499302295931 Marionette TRACE 354 <- [1,1,null,{"sessionId":"c99fd678-a098-413c-a3b3-1b264c713f7e","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:35 INFO - 1499302295933 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:51:35 INFO - 1499302295934 Marionette TRACE 354 <- [1,2,null,{}]
17:51:35 INFO - 1499302295935 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:51:35 INFO - 1499302295935 Marionette TRACE 354 <- [1,3,null,{}]
17:51:35 INFO - 1499302295936 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:51:35 INFO - 1499302295937 Marionette TRACE 354 <- [1,4,null,{}]
17:51:35 INFO - 1499302295939 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
17:51:35 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499302295942 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:51:35 INFO - 1499302295943 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:51:35 INFO - 1499302295944 Marionette TRACE 354 <- [1,6,null,{}]
17:51:35 INFO - 1499302295946 Marionette DEBUG Closed connection 354
17:51:35 INFO - 1499302295947 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49842
17:51:35 INFO - 1499302295948 Marionette DEBUG Closed connection 355
17:51:35 INFO - 1499302295949 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49843
17:51:35 INFO - 1499302295950 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:35 INFO - 1499302295951 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:35 INFO - 1499302295953 Marionette DEBUG Register listener.js for window 12
17:51:35 INFO - 1499302295957 Marionette TRACE 356 <- [1,1,null,{"sessionId":"bdd47347-e1d5-4019-9ce3-ddf8985b2111","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:35 INFO - 1499302295958 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:51:35 INFO - 1499302295959 Marionette TRACE 356 <- [1,2,null,{}]
17:51:35 INFO - 1499302295962 Marionette DEBUG Closed connection 356
17:51:35 INFO - 1499302295962 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49844
17:51:35 INFO - 1499302295963 Marionette DEBUG Closed connection 357
17:51:35 INFO - 1499302295964 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49845
17:51:35 INFO - 1499302295965 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:51:35 INFO - 1499302295966 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:35 INFO - 1499302295968 Marionette DEBUG Register listener.js for window 12
17:51:35 INFO - 1499302295972 Marionette TRACE 358 <- [1,1,null,{"sessionId":"138550cb-b11c-468a-b838-eeee64a47cf4","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:35 INFO - 1499302295974 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49405/clicks.html"}]
17:51:35 INFO - 1499302295976 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49405/hidden.html"
17:51:35 INFO - 1499302295981 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49405/hidden.html"
17:51:35 INFO - 1499302295982 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49405/hidden.html"
17:51:36 INFO - 1499302296002 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49405/clicks.html"
17:51:36 INFO - 1499302296011 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49405/clicks.html"
17:51:36 INFO - 1499302296015 Marionette TRACE 358 <- [1,2,null,{}]
17:51:36 INFO - 1499302296017 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:51:36 INFO - 1499302296019 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0d08a11e-d350-4110-9951-c6ef906d7967","ELEMENT":"0d08a11e-d350-4110-9951-c6ef906d7967"}}]
17:51:36 INFO - 1499302296028 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0d08a11e-d350-4110-9951-c6ef906d7967"}]
17:51:36 INFO - 1499302296082 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49405/clicks.html"
17:51:36 INFO - 1499302296094 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49405/clicks.html"
17:51:36 INFO - 1499302296095 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49405/clicks.html"
17:51:36 INFO - 1499302296129 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49405/test.html"
17:51:36 INFO - 1499302296133 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49405/test.html"
17:51:36 INFO - 1499302296138 Marionette TRACE 358 <- [1,4,null,{}]
17:51:36 INFO - 1499302296148 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:51:36 INFO - 1499302296151 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"072addce-7c9d-47b7-9162-4661c5b6c1c2","ELEMENT":"072addce-7c9d-47b7-9162-4661c5b6c1c2"}}]
17:51:36 INFO - 1499302296152 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:51:36 INFO - 1499302296153 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:51:36 INFO - 1499302296159 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
17:51:36 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499302296162 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:51:36 INFO - 1499302296163 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:51:36 INFO - 1499302296165 Marionette TRACE 358 <- [1,8,null,{}]
17:51:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 249ms
17:51:36 INFO - TEST-START | test_click.py TestClick.test_container_element
17:51:36 INFO - 1499302296167 Marionette DEBUG Closed connection 358
17:51:36 INFO - 1499302296168 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49848
17:51:36 INFO - 1499302296169 Marionette DEBUG Closed connection 359
17:51:36 INFO - 1499302296170 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49849
17:51:36 INFO - 1499302296171 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:36 INFO - 1499302296172 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296175 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296178 Marionette TRACE 360 <- [1,1,null,{"sessionId":"326d63cf-807c-4c02-bbbe-7f34a7011a85","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296180 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:51:36 INFO - 1499302296180 Marionette TRACE 360 <- [1,2,null,{}]
17:51:36 INFO - 1499302296182 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:51:36 INFO - 1499302296183 Marionette TRACE 360 <- [1,3,null,{}]
17:51:36 INFO - 1499302296184 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:51:36 INFO - 1499302296184 Marionette TRACE 360 <- [1,4,null,{}]
17:51:36 INFO - 1499302296186 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
17:51:36 INFO - TEST-START: test_click.py TestClick.test_container_element1499302296189 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:51:36 INFO - 1499302296190 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:51:36 INFO - 1499302296192 Marionette TRACE 360 <- [1,6,null,{}]
17:51:36 INFO - 1499302296194 Marionette DEBUG Closed connection 360
17:51:36 INFO - 1499302296195 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49850
17:51:36 INFO - 1499302296196 Marionette DEBUG Closed connection 361
17:51:36 INFO - 1499302296196 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49851
17:51:36 INFO - 1499302296198 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:36 INFO - 1499302296198 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296201 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296204 Marionette TRACE 362 <- [1,1,null,{"sessionId":"07bdfda7-026d-49b4-93b5-097a9929c22f","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296205 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:51:36 INFO - 1499302296207 Marionette TRACE 362 <- [1,2,null,{}]
17:51:36 INFO - 1499302296209 Marionette DEBUG Closed connection 362
17:51:36 INFO - 1499302296210 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49852
17:51:36 INFO - 1499302296211 Marionette DEBUG Closed connection 363
17:51:36 INFO - 1499302296211 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49853
17:51:36 INFO - 1499302296213 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:51:36 INFO - 1499302296213 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296216 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296219 Marionette TRACE 364 <- [1,1,null,{"sessionId":"add977dd-b05e-4e40-8cf5-688bd4e810d4","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296221 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:51:36 INFO - 1499302296222 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49405/test.html"
17:51:36 INFO - 1499302296225 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49405/test.html"
17:51:36 INFO - 1499302296225 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49405/test.html"
17:51:36 INFO - 1499302296252 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"
17:51:36 INFO - 1499302296257 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"
17:51:36 INFO - 1499302296268 Marionette TRACE 364 <- [1,2,null,{}]
17:51:36 INFO - 1499302296269 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:51:36 INFO - 1499302296272 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eb9968fe-874a-47a1-9bfd-bc3b7b376c8a","ELEMENT":"eb9968fe-874a-47a1-9bfd-bc3b7b376c8a"}}]
17:51:36 INFO - 1499302296275 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"eb9968fe-874a-47a1-9bfd-bc3b7b376c8a"}]
17:51:36 INFO - 1499302296519 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:51:36 INFO - 1499302296520 Marionette TRACE 364 <- [1,4,null,{}]
17:51:36 INFO - 1499302296522 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"eb9968fe-874a-47a1-9bfd-bc3b7b376c8a","name":"selected"}]
17:51:36 INFO - 1499302296527 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:51:36 INFO - 1499302296529 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
17:51:36 INFO - TEST-END: test_click.py TestClick.test_container_element1499302296532 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:51:36 INFO - 1499302296533 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:51:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
17:51:36 INFO - 1499302296534 Marionette TRACE 364 <- [1,7,null,{}]
17:51:36 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:51:36 INFO - 1499302296536 Marionette DEBUG Closed connection 364
17:51:36 INFO - 1499302296538 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49854
17:51:36 INFO - 1499302296540 Marionette DEBUG Closed connection 365
17:51:36 INFO - 1499302296540 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49855
17:51:36 INFO - 1499302296542 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:36 INFO - 1499302296542 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296545 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296549 Marionette TRACE 366 <- [1,1,null,{"sessionId":"de0b802d-fdb4-432f-9fe6-30915697047d","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296551 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:51:36 INFO - 1499302296551 Marionette TRACE 366 <- [1,2,null,{}]
17:51:36 INFO - 1499302296552 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:51:36 INFO - 1499302296553 Marionette TRACE 366 <- [1,3,null,{}]
17:51:36 INFO - 1499302296554 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:51:36 INFO - 1499302296554 Marionette TRACE 366 <- [1,4,null,{}]
17:51:36 INFO - 1499302296556 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
17:51:36 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499302296559 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:51:36 INFO - 1499302296560 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:51:36 INFO - 1499302296561 Marionette TRACE 366 <- [1,6,null,{}]
17:51:36 INFO - 1499302296564 Marionette DEBUG Closed connection 366
17:51:36 INFO - 1499302296564 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49856
17:51:36 INFO - 1499302296565 Marionette DEBUG Closed connection 367
17:51:36 INFO - 1499302296566 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49857
17:51:36 INFO - 1499302296567 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:36 INFO - 1499302296568 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296570 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296574 Marionette TRACE 368 <- [1,1,null,{"sessionId":"5f3bad43-90c5-43e2-8ff4-70c1e1f0e088","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296575 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:51:36 INFO - 1499302296576 Marionette TRACE 368 <- [1,2,null,{}]
17:51:36 INFO - 1499302296579 Marionette DEBUG Closed connection 368
17:51:36 INFO - 1499302296580 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49858
17:51:36 INFO - 1499302296580 Marionette DEBUG Closed connection 369
17:51:36 INFO - 1499302296581 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49859
17:51:36 INFO - 1499302296582 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:51:36 INFO - 1499302296583 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296586 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296589 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3e8db861-e5c3-449a-9892-937334934572","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296590 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:51:36 INFO - 1499302296593 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"
17:51:36 INFO - 1499302296596 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"
17:51:36 INFO - 1499302296596 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"
17:51:36 INFO - 1499302296616 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"
17:51:36 INFO - 1499302296620 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"
17:51:36 INFO - 1499302296630 Marionette TRACE 370 <- [1,2,null,{}]
17:51:36 INFO - 1499302296631 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:51:36 INFO - 1499302296634 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c4727cf-c2a3-4781-9370-5a7003a5d4c0","ELEMENT":"3c4727cf-c2a3-4781-9370-5a7003a5d4c0"}}]
17:51:36 INFO - 1499302296635 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"3c4727cf-c2a3-4781-9370-5a7003a5d4c0"}]
17:51:36 INFO - 1499302296889 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:51:36 INFO - 1499302296890 Marionette TRACE 370 <- [1,4,null,{}]
17:51:36 INFO - 1499302296891 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"3c4727cf-c2a3-4781-9370-5a7003a5d4c0","name":"selected"}]
17:51:36 INFO - 1499302296896 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:51:36 INFO - 1499302296898 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
17:51:36 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499302296901 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:51:36 INFO - 1499302296902 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:51:36 INFO - 1499302296903 Marionette TRACE 370 <- [1,7,null,{}]
17:51:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
17:51:36 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:51:36 INFO - 1499302296906 Marionette DEBUG Closed connection 370
17:51:36 INFO - 1499302296907 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49860
17:51:36 INFO - 1499302296907 Marionette DEBUG Closed connection 371
17:51:36 INFO - 1499302296908 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49861
17:51:36 INFO - 1499302296909 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:36 INFO - 1499302296910 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296913 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296916 Marionette TRACE 372 <- [1,1,null,{"sessionId":"4804721e-df54-4097-b8ac-9a130e4c332e","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296918 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:51:36 INFO - 1499302296918 Marionette TRACE 372 <- [1,2,null,{}]
17:51:36 INFO - 1499302296919 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:51:36 INFO - 1499302296920 Marionette TRACE 372 <- [1,3,null,{}]
17:51:36 INFO - 1499302296921 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:51:36 INFO - 1499302296921 Marionette TRACE 372 <- [1,4,null,{}]
17:51:36 INFO - 1499302296923 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
17:51:36 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499302296926 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:51:36 INFO - 1499302296927 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:51:36 INFO - 1499302296928 Marionette TRACE 372 <- [1,6,null,{}]
17:51:36 INFO - 1499302296930 Marionette DEBUG Closed connection 372
17:51:36 INFO - 1499302296931 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49862
17:51:36 INFO - 1499302296932 Marionette DEBUG Closed connection 373
17:51:36 INFO - 1499302296933 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49863
17:51:36 INFO - 1499302296934 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:36 INFO - 1499302296934 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296937 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296941 Marionette TRACE 374 <- [1,1,null,{"sessionId":"597b73d1-04e2-4c1d-8e5d-f900b3a67844","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296942 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:51:36 INFO - 1499302296943 Marionette TRACE 374 <- [1,2,null,{}]
17:51:36 INFO - 1499302296945 Marionette DEBUG Closed connection 374
17:51:36 INFO - 1499302296946 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49864
17:51:36 INFO - 1499302296947 Marionette DEBUG Closed connection 375
17:51:36 INFO - 1499302296948 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49865
17:51:36 INFO - 1499302296949 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:51:36 INFO - 1499302296949 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:36 INFO - 1499302296952 Marionette DEBUG Register listener.js for window 12
17:51:36 INFO - 1499302296955 Marionette TRACE 376 <- [1,1,null,{"sessionId":"d967262c-6ca9-4ab0-92d9-7b583a7c9586","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:36 INFO - 1499302296957 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
17:51:36 INFO - 1499302296959 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"
17:51:36 INFO - 1499302296963 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"
17:51:36 INFO - 1499302296964 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"
17:51:36 INFO - 1499302296985 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"
17:51:36 INFO - 1499302296989 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"
17:51:37 INFO - 1499302296999 Marionette TRACE 376 <- [1,2,null,{}]
17:51:37 INFO - 1499302297001 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:51:37 INFO - 1499302297004 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"645a50b7-ae83-4415-9c30-b13ebec00016","ELEMENT":"645a50b7-ae83-4415-9c30-b13ebec00016"}}]
17:51:37 INFO - 1499302297006 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"645a50b7-ae83-4415-9c30-b13ebec00016"}]
17:51:37 INFO - 1499302297248 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:51:37 INFO - 1499302297249 Marionette TRACE 376 <- [1,4,null,{}]
17:51:37 INFO - 1499302297251 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
17:51:37 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499302297254 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:51:37 INFO - 1499302297255 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:51:37 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 353ms
17:51:37 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:51:37 INFO - 1499302297257 Marionette TRACE 376 <- [1,6,null,{}]
17:51:37 INFO - 1499302297259 Marionette DEBUG Closed connection 376
17:51:37 INFO - 1499302297260 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49866
17:51:37 INFO - 1499302297261 Marionette DEBUG Closed connection 377
17:51:37 INFO - 1499302297262 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49867
17:51:37 INFO - 1499302297263 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:37 INFO - 1499302297263 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297266 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297270 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9901f690-1082-4d64-894e-7ec8eafd85a5","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297271 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:51:37 INFO - 1499302297272 Marionette TRACE 378 <- [1,2,null,{}]
17:51:37 INFO - 1499302297272 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:51:37 INFO - 1499302297273 Marionette TRACE 378 <- [1,3,null,{}]
17:51:37 INFO - 1499302297274 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:51:37 INFO - 1499302297274 Marionette TRACE 378 <- [1,4,null,{}]
17:51:37 INFO - 1499302297276 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
17:51:37 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499302297279 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:51:37 INFO - 1499302297280 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:51:37 INFO - 1499302297281 Marionette TRACE 378 <- [1,6,null,{}]
17:51:37 INFO - 1499302297284 Marionette DEBUG Closed connection 378
17:51:37 INFO - 1499302297284 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49868
17:51:37 INFO - 1499302297285 Marionette DEBUG Closed connection 379
17:51:37 INFO - 1499302297286 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49869
17:51:37 INFO - 1499302297287 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:37 INFO - 1499302297288 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297291 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297294 Marionette TRACE 380 <- [1,1,null,{"sessionId":"5db8fb8a-6165-4d1c-9ae5-b25f72199930","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297296 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:51:37 INFO - 1499302297297 Marionette TRACE 380 <- [1,2,null,{}]
17:51:37 INFO - 1499302297299 Marionette DEBUG Closed connection 380
17:51:37 INFO - 1499302297300 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49870
17:51:37 INFO - 1499302297301 Marionette DEBUG Closed connection 381
17:51:37 INFO - 1499302297302 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49871
17:51:37 INFO - 1499302297303 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:51:37 INFO - 1499302297303 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297306 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297310 Marionette TRACE 382 <- [1,1,null,{"sessionId":"8f0c1cad-88cc-4529-99d4-0af4715cb7a4","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297311 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
17:51:37 INFO - 1499302297312 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"
17:51:37 INFO - 1499302297315 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"
17:51:37 INFO - 1499302297315 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"
17:51:37 INFO - 1499302297335 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"
17:51:37 INFO - 1499302297339 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"
17:51:37 INFO - 1499302297350 Marionette TRACE 382 <- [1,2,null,{}]
17:51:37 INFO - 1499302297351 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:51:37 INFO - 1499302297354 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5716e532-b50b-4fb9-9728-a2d3848c81c8","ELEMENT":"5716e532-b50b-4fb9-9728-a2d3848c81c8"}}]
17:51:37 INFO - 1499302297357 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"5716e532-b50b-4fb9-9728-a2d3848c81c8"}]
17:51:37 INFO - 1499302297609 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:51:37 INFO - 1499302297610 Marionette TRACE 382 <- [1,4,null,{}]
17:51:37 INFO - 1499302297612 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
17:51:37 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499302297615 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:51:37 INFO - 1499302297616 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:51:37 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 360ms
17:51:37 INFO - TEST-START | test_click.py TestClick.test_input_file
17:51:37 INFO - 1499302297617 Marionette TRACE 382 <- [1,6,null,{}]
17:51:37 INFO - 1499302297619 Marionette DEBUG Closed connection 382
17:51:37 INFO - 1499302297620 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49872
17:51:37 INFO - 1499302297621 Marionette DEBUG Closed connection 383
17:51:37 INFO - 1499302297622 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49873
17:51:37 INFO - 1499302297623 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:37 INFO - 1499302297623 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297626 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297630 Marionette TRACE 384 <- [1,1,null,{"sessionId":"bf7c2040-71af-40a7-a10a-63fb0f6500f5","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297631 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:51:37 INFO - 1499302297631 Marionette TRACE 384 <- [1,2,null,{}]
17:51:37 INFO - 1499302297633 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:51:37 INFO - 1499302297633 Marionette TRACE 384 <- [1,3,null,{}]
17:51:37 INFO - 1499302297634 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:51:37 INFO - 1499302297635 Marionette TRACE 384 <- [1,4,null,{}]
17:51:37 INFO - 1499302297636 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
17:51:37 INFO - TEST-START: test_click.py TestClick.test_input_file1499302297639 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:51:37 INFO - 1499302297640 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:51:37 INFO - 1499302297642 Marionette TRACE 384 <- [1,6,null,{}]
17:51:37 INFO - 1499302297644 Marionette DEBUG Closed connection 384
17:51:37 INFO - 1499302297645 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49874
17:51:37 INFO - 1499302297646 Marionette DEBUG Closed connection 385
17:51:37 INFO - 1499302297646 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49875
17:51:37 INFO - 1499302297648 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:37 INFO - 1499302297648 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297651 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297654 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d02a99bb-770a-479c-892d-f5c081165e80","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297655 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:51:37 INFO - 1499302297657 Marionette TRACE 386 <- [1,2,null,{}]
17:51:37 INFO - 1499302297659 Marionette DEBUG Closed connection 386
17:51:37 INFO - 1499302297660 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49876
17:51:37 INFO - 1499302297661 Marionette DEBUG Closed connection 387
17:51:37 INFO - 1499302297661 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49877
17:51:37 INFO - 1499302297663 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:51:37 INFO - 1499302297663 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297666 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297669 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1cfaf34b-9954-4b54-ae32-b98e4c792810","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297672 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:51:37 INFO - 1499302297673 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"
17:51:37 INFO - 1499302297676 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"
17:51:37 INFO - 1499302297676 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"
17:51:37 INFO - 1499302297698 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:51:37 INFO - 1499302297700 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:51:37 INFO - 1499302297704 Marionette TRACE 388 <- [1,2,null,{}]
17:51:37 INFO - 1499302297713 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:51:37 INFO - 1499302297716 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a51afc12-ae43-4853-820b-8b0f3b2596a5","ELEMENT":"a51afc12-ae43-4853-820b-8b0f3b2596a5"}}]
17:51:37 INFO - 1499302297717 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"a51afc12-ae43-4853-820b-8b0f3b2596a5"}]
17:51:37 INFO - 1499302297746 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:51:37 INFO - 1499302297748 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
17:51:37 INFO - TEST-END: test_click.py TestClick.test_input_file1499302297751 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:51:37 INFO - 1499302297752 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:51:37 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 137ms
17:51:37 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:51:37 INFO - 1499302297754 Marionette TRACE 388 <- [1,6,null,{}]
17:51:37 INFO - 1499302297756 Marionette DEBUG Closed connection 388
17:51:37 INFO - 1499302297757 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49878
17:51:37 INFO - 1499302297758 Marionette DEBUG Closed connection 389
17:51:37 INFO - 1499302297759 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49879
17:51:37 INFO - 1499302297760 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:37 INFO - 1499302297760 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297763 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297767 Marionette TRACE 390 <- [1,1,null,{"sessionId":"2ecae828-a8fb-44f1-9738-def21c1f00af","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297768 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:51:37 INFO - 1499302297769 Marionette TRACE 390 <- [1,2,null,{}]
17:51:37 INFO - 1499302297769 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:51:37 INFO - 1499302297770 Marionette TRACE 390 <- [1,3,null,{}]
17:51:37 INFO - 1499302297771 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:51:37 INFO - 1499302297772 Marionette TRACE 390 <- [1,4,null,{}]
17:51:37 INFO - 1499302297773 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
17:51:37 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499302297776 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:51:37 INFO - 1499302297777 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:51:37 INFO - 1499302297778 Marionette TRACE 390 <- [1,6,null,{}]
17:51:37 INFO - 1499302297781 Marionette DEBUG Closed connection 390
17:51:37 INFO - 1499302297781 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49880
17:51:37 INFO - 1499302297782 Marionette DEBUG Closed connection 391
17:51:37 INFO - 1499302297783 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49881
17:51:37 INFO - 1499302297784 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:37 INFO - 1499302297785 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297787 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297791 Marionette TRACE 392 <- [1,1,null,{"sessionId":"1b0f2f09-811e-4103-a4b8-7cd724a548df","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297793 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:51:37 INFO - 1499302297794 Marionette TRACE 392 <- [1,2,null,{}]
17:51:37 INFO - 1499302297796 Marionette DEBUG Closed connection 392
17:51:37 INFO - 1499302297797 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49882
17:51:37 INFO - 1499302297798 Marionette DEBUG Closed connection 393
17:51:37 INFO - 1499302297799 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49883
17:51:37 INFO - 1499302297800 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:51:37 INFO - 1499302297800 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:37 INFO - 1499302297803 Marionette DEBUG Register listener.js for window 12
17:51:37 INFO - 1499302297806 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a3a7cab7-87fe-400b-a662-00e4c6565199","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:37 INFO - 1499302297808 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
17:51:37 INFO - 1499302297809 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:51:37 INFO - 1499302297812 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:51:37 INFO - 1499302297812 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:51:37 INFO - 1499302297835 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"
17:51:37 INFO - 1499302297838 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"
17:51:37 INFO - 1499302297848 Marionette TRACE 394 <- [1,2,null,{}]
17:51:37 INFO - 1499302297849 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:51:37 INFO - 1499302297852 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8862d823-dc02-4606-b59a-b7396797f36d","ELEMENT":"8862d823-dc02-4606-b59a-b7396797f36d"}}]
17:51:37 INFO - 1499302297853 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:51:37 INFO - 1499302297856 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a7b5823b-6340-4b67-91bc-c412d4a75f9f","ELEMENT":"a7b5823b-6340-4b67-91bc-c412d4a75f9f"}}]
17:51:37 INFO - 1499302297858 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"8862d823-dc02-4606-b59a-b7396797f36d"}]
17:51:38 INFO - 1499302298099 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:51:38 INFO - 1499302298100 Marionette TRACE 394 <- [1,5,null,{}]
17:51:38 INFO - 1499302298101 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a7b5823b-6340-4b67-91bc-c412d4a75f9f"}]
17:51:38 INFO - 1499302298119 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:51:38 INFO - 1499302298121 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:51:38 INFO - 1499302298124 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:51:38 INFO - 1499302298126 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
17:51:38 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499302298129 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:51:38 INFO - 1499302298130 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:51:38 INFO - 1499302298132 Marionette TRACE 394 <- [1,9,null,{}]
17:51:38 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 378ms
17:51:38 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:51:38 INFO - 1499302298134 Marionette DEBUG Closed connection 394
17:51:38 INFO - 1499302298135 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49884
17:51:38 INFO - 1499302298135 Marionette DEBUG Closed connection 395
17:51:38 INFO - 1499302298136 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49885
17:51:38 INFO - 1499302298137 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:38 INFO - 1499302298138 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:38 INFO - 1499302298141 Marionette DEBUG Register listener.js for window 12
17:51:38 INFO - 1499302298152 Marionette TRACE 396 <- [1,1,null,{"sessionId":"daf2507a-4786-4293-8288-47a4c070e137","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:38 INFO - 1499302298154 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:51:38 INFO - 1499302298154 Marionette TRACE 396 <- [1,2,null,{}]
17:51:38 INFO - 1499302298155 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:51:38 INFO - 1499302298156 Marionette TRACE 396 <- [1,3,null,{}]
17:51:38 INFO - 1499302298157 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:51:38 INFO - 1499302298157 Marionette TRACE 396 <- [1,4,null,{}]
17:51:38 INFO - 1499302298159 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
17:51:38 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499302298162 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:51:38 INFO - 1499302298163 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:51:38 INFO - 1499302298165 Marionette TRACE 396 <- [1,6,null,{}]
17:51:38 INFO - 1499302298167 Marionette DEBUG Closed connection 396
17:51:38 INFO - 1499302298168 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49886
17:51:38 INFO - 1499302298169 Marionette DEBUG Closed connection 397
17:51:38 INFO - 1499302298170 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49887
17:51:38 INFO - 1499302298171 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:51:38 INFO - 1499302298171 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:38 INFO - 1499302298174 Marionette DEBUG Register listener.js for window 12
17:51:38 INFO - 1499302298178 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5e20142d-a0fc-4d34-9a95-0a4aa39b76e5","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:38 INFO - 1499302298179 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:51:38 INFO - 1499302298181 Marionette TRACE 398 <- [1,2,null,{}]
17:51:38 INFO - 1499302298183 Marionette DEBUG Closed connection 398
17:51:38 INFO - 1499302298184 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49888
17:51:38 INFO - 1499302298185 Marionette DEBUG Closed connection 399
17:51:38 INFO - 1499302298186 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49889
17:51:38 INFO - 1499302298187 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:51:38 INFO - 1499302298187 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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}
17:51:38 INFO - 1499302298190 Marionette DEBUG Register listener.js for window 12
17:51:38 INFO - 1499302298193 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4a5bc0d1-4cdd-41ac-8d9c-25b4605a8748","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":2248,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpwj1m7f.mozrunner","moz:accessibilityChecks":false}}]
17:51:38 INFO - 1499302298195 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
17:51:38 INFO - 1499302298196 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"
17:51:38 INFO - 1499302298199 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"
17:51:38 INFO - 1499302298199 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"
17:51:38 INFO - 1499302298220 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"
17:51:38 INFO - 1499302298224 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"
17:51:38 INFO - 1499302298233 Marionette TRACE 400 <- [1,2,null,{}]
17:51:38 INFO - 1499302298235 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:51:38 INFO - 1499302298238 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"060b5c3d-b836-4721-8474-6b613753302c","ELEMENT":"060b5c3d-b836-4721-8474-6b613753302c"}}]
17:51:38 INFO - 1499302298239 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"060b5c3d-b836-4721-8474-6b613753302c"}]
17:51:38 INFO - 1499302298263 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:51:38 INFO - 1499302298265 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"060b5c3d-b836-4721-8474-6b613753302c"}]
17:51:38 INFO - 1499302298283 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element