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]
05:07:23 INFO - 1499256443962 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}]
05:07:23 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499256443966 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:07:23 INFO - 1499256443967 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:07:23 INFO - 1499256443968 Marionette TRACE 352 <- [1,6,null,{}]
05:07:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 164ms
05:07:23 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:07:23 INFO - 1499256443971 Marionette DEBUG Closed connection 352
05:07:23 INFO - 1499256443972 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49868
05:07:23 INFO - 1499256443973 Marionette DEBUG Closed connection 353
05:07:23 INFO - 1499256443973 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49869
05:07:23 INFO - 1499256443975 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:23 INFO - 1499256443975 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:23 INFO - 1499256443978 Marionette DEBUG Register listener.js for window 12
05:07:23 INFO - 1499256443983 Marionette TRACE 354 <- [1,1,null,{"sessionId":"9c85954c-2c7c-48f9-9300-fe4f880759b2","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:23 INFO - 1499256443984 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:07:23 INFO - 1499256443985 Marionette TRACE 354 <- [1,2,null,{}]
05:07:23 INFO - 1499256443986 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:07:23 INFO - 1499256443986 Marionette TRACE 354 <- [1,3,null,{}]
05:07:23 INFO - 1499256443987 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:07:23 INFO - 1499256443988 Marionette TRACE 354 <- [1,4,null,{}]
05:07:23 INFO - 1499256443990 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}]
05:07:23 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499256443993 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:07:23 INFO - 1499256443994 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:07:23 INFO - 1499256443996 Marionette TRACE 354 <- [1,6,null,{}]
05:07:24 INFO - 1499256443999 Marionette DEBUG Closed connection 354
05:07:24 INFO - 1499256444000 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49870
05:07:24 INFO - 1499256444001 Marionette DEBUG Closed connection 355
05:07:24 INFO - 1499256444002 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49871
05:07:24 INFO - 1499256444003 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:24 INFO - 1499256444004 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:24 INFO - 1499256444007 Marionette DEBUG Register listener.js for window 12
05:07:24 INFO - 1499256444010 Marionette TRACE 356 <- [1,1,null,{"sessionId":"35e9692a-a0d1-4bc0-a5da-f2606eec993e","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:24 INFO - 1499256444012 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:07:24 INFO - 1499256444013 Marionette TRACE 356 <- [1,2,null,{}]
05:07:24 INFO - 1499256444016 Marionette DEBUG Closed connection 356
05:07:24 INFO - 1499256444017 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49872
05:07:24 INFO - 1499256444018 Marionette DEBUG Closed connection 357
05:07:24 INFO - 1499256444019 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49873
05:07:24 INFO - 1499256444020 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:07:24 INFO - 1499256444021 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:24 INFO - 1499256444024 Marionette DEBUG Register listener.js for window 12
05:07:24 INFO - 1499256444027 Marionette TRACE 358 <- [1,1,null,{"sessionId":"51a417ae-b57c-4e83-abc4-f18a312b41bb","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:24 INFO - 1499256444029 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49434/clicks.html"}]
05:07:24 INFO - 1499256444031 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49434/hidden.html"
05:07:24 INFO - 1499256444037 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49434/hidden.html"
05:07:24 INFO - 1499256444037 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49434/hidden.html"
05:07:24 INFO - 1499256444059 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49434/clicks.html"
05:07:24 INFO - 1499256444069 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49434/clicks.html"
05:07:24 INFO - 1499256444074 Marionette TRACE 358 <- [1,2,null,{}]
05:07:24 INFO - 1499256444076 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:07:24 INFO - 1499256444088 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5da3666b-9dc9-4e6b-b8be-7bf914c9cfec","ELEMENT":"5da3666b-9dc9-4e6b-b8be-7bf914c9cfec"}}]
05:07:24 INFO - 1499256444090 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5da3666b-9dc9-4e6b-b8be-7bf914c9cfec"}]
05:07:24 INFO - 1499256444144 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49434/clicks.html"
05:07:24 INFO - 1499256444156 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49434/clicks.html"
05:07:24 INFO - 1499256444157 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49434/clicks.html"
05:07:24 INFO - 1499256444193 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49434/test.html"
05:07:24 INFO - 1499256444198 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49434/test.html"
05:07:24 INFO - 1499256444212 Marionette TRACE 358 <- [1,4,null,{}]
05:07:24 INFO - 1499256444216 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:07:24 INFO - 1499256444219 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2303a8a0-ad14-4511-9ffa-558130f287aa","ELEMENT":"2303a8a0-ad14-4511-9ffa-558130f287aa"}}]
05:07:24 INFO - 1499256444221 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:07:24 INFO - 1499256444221 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:07:24 INFO - 1499256444223 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}]
05:07:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499256444226 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:07:24 INFO - 1499256444228 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:07:24 INFO - 1499256444229 Marionette TRACE 358 <- [1,8,null,{}]
05:07:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 261ms
05:07:24 INFO - TEST-START | test_click.py TestClick.test_container_element
05:07:24 INFO - 1499256444232 Marionette DEBUG Closed connection 358
05:07:24 INFO - 1499256444233 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49876
05:07:24 INFO - 1499256444234 Marionette DEBUG Closed connection 359
05:07:24 INFO - 1499256444234 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49877
05:07:24 INFO - 1499256444236 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:24 INFO - 1499256444236 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:24 INFO - 1499256444239 Marionette DEBUG Register listener.js for window 12
05:07:24 INFO - 1499256444243 Marionette TRACE 360 <- [1,1,null,{"sessionId":"95cd5d7a-7b78-44bb-a814-9057773cb0d6","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:24 INFO - 1499256444245 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:07:24 INFO - 1499256444246 Marionette TRACE 360 <- [1,2,null,{}]
05:07:24 INFO - 1499256444247 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:07:24 INFO - 1499256444247 Marionette TRACE 360 <- [1,3,null,{}]
05:07:24 INFO - 1499256444248 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:07:24 INFO - 1499256444249 Marionette TRACE 360 <- [1,4,null,{}]
05:07:24 INFO - 1499256444251 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}]
05:07:24 INFO - TEST-START: test_click.py TestClick.test_container_element1499256444254 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:07:24 INFO - 1499256444256 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:07:24 INFO - 1499256444257 Marionette TRACE 360 <- [1,6,null,{}]
05:07:24 INFO - 1499256444260 Marionette DEBUG Closed connection 360
05:07:24 INFO - 1499256444261 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49878
05:07:24 INFO - 1499256444262 Marionette DEBUG Closed connection 361
05:07:24 INFO - 1499256444263 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49879
05:07:24 INFO - 1499256444264 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:24 INFO - 1499256444264 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:24 INFO - 1499256444267 Marionette DEBUG Register listener.js for window 12
05:07:24 INFO - 1499256444271 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a22a2eb2-01c1-4820-b945-04c3aa9e4ac4","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:24 INFO - 1499256444273 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:07:24 INFO - 1499256444274 Marionette TRACE 362 <- [1,2,null,{}]
05:07:24 INFO - 1499256444276 Marionette DEBUG Closed connection 362
05:07:24 INFO - 1499256444277 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49880
05:07:24 INFO - 1499256444279 Marionette DEBUG Closed connection 363
05:07:24 INFO - 1499256444280 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49881
05:07:24 INFO - 1499256444281 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:07:24 INFO - 1499256444282 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:24 INFO - 1499256444285 Marionette DEBUG Register listener.js for window 12
05:07:24 INFO - 1499256444289 Marionette TRACE 364 <- [1,1,null,{"sessionId":"3ff79631-4dda-4480-b49f-9c7f2fd4b905","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:24 INFO - 1499256444291 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"}]
05:07:24 INFO - 1499256444293 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49434/test.html"
05:07:24 INFO - 1499256444295 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49434/test.html"
05:07:24 INFO - 1499256444296 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49434/test.html"
05:07:24 INFO - 1499256444324 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"
05:07:24 INFO - 1499256444330 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"
05:07:24 INFO - 1499256444342 Marionette TRACE 364 <- [1,2,null,{}]
05:07:24 INFO - 1499256444343 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:07:24 INFO - 1499256444347 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8d1ca08-8500-4ef3-a7b2-ebe3b02464f5","ELEMENT":"e8d1ca08-8500-4ef3-a7b2-ebe3b02464f5"}}]
05:07:24 INFO - 1499256444349 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e8d1ca08-8500-4ef3-a7b2-ebe3b02464f5"}]
05:07:24 INFO - 1499256444595 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:07:24 INFO - 1499256444596 Marionette TRACE 364 <- [1,4,null,{}]
05:07:24 INFO - 1499256444605 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e8d1ca08-8500-4ef3-a7b2-ebe3b02464f5","name":"selected"}]
05:07:24 INFO - 1499256444611 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:07:24 INFO - 1499256444615 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}]
05:07:24 INFO - TEST-END: test_click.py TestClick.test_container_element1499256444618 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:07:24 INFO - 1499256444620 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:07:24 INFO - 1499256444621 Marionette TRACE 364 <- [1,7,null,{}]
05:07:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 392ms
05:07:24 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:07:24 INFO - 1499256444624 Marionette DEBUG Closed connection 364
05:07:24 INFO - 1499256444625 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49882
05:07:24 INFO - 1499256444626 Marionette DEBUG Closed connection 365
05:07:24 INFO - 1499256444626 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49883
05:07:24 INFO - 1499256444627 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:24 INFO - 1499256444628 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:24 INFO - 1499256444631 Marionette DEBUG Register listener.js for window 12
05:07:24 INFO - 1499256444635 Marionette TRACE 366 <- [1,1,null,{"sessionId":"706e7317-ee3b-4e34-bad2-56a9ddc885b9","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:24 INFO - 1499256444642 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:07:24 INFO - 1499256444642 Marionette TRACE 366 <- [1,2,null,{}]
05:07:24 INFO - 1499256444643 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:07:24 INFO - 1499256444644 Marionette TRACE 366 <- [1,3,null,{}]
05:07:24 INFO - 1499256444645 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:07:24 INFO - 1499256444646 Marionette TRACE 366 <- [1,4,null,{}]
05:07:24 INFO - 1499256444648 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}]
05:07:24 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499256444652 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:07:24 INFO - 1499256444653 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:07:24 INFO - 1499256444655 Marionette TRACE 366 <- [1,6,null,{}]
05:07:24 INFO - 1499256444657 Marionette DEBUG Closed connection 366
05:07:24 INFO - 1499256444658 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49884
05:07:24 INFO - 1499256444659 Marionette DEBUG Closed connection 367
05:07:24 INFO - 1499256444660 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49885
05:07:24 INFO - 1499256444661 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:24 INFO - 1499256444662 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:24 INFO - 1499256444665 Marionette DEBUG Register listener.js for window 12
05:07:24 INFO - 1499256444669 Marionette TRACE 368 <- [1,1,null,{"sessionId":"8a050990-83af-40bf-bc4e-214fd132e78d","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:24 INFO - 1499256444670 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:07:24 INFO - 1499256444672 Marionette TRACE 368 <- [1,2,null,{}]
05:07:24 INFO - 1499256444674 Marionette DEBUG Closed connection 368
05:07:24 INFO - 1499256444675 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49886
05:07:24 INFO - 1499256444676 Marionette DEBUG Closed connection 369
05:07:24 INFO - 1499256444677 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49887
05:07:24 INFO - 1499256444678 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:07:24 INFO - 1499256444679 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:24 INFO - 1499256444682 Marionette DEBUG Register listener.js for window 12
05:07:24 INFO - 1499256444686 Marionette TRACE 370 <- [1,1,null,{"sessionId":"08a8afa8-8387-4081-8f18-2dbb7028bc37","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:24 INFO - 1499256444688 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"}]
05:07:24 INFO - 1499256444690 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"
05:07:24 INFO - 1499256444694 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"
05:07:24 INFO - 1499256444695 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"
05:07:24 INFO - 1499256444716 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"
05:07:24 INFO - 1499256444721 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"
05:07:24 INFO - 1499256444725 Marionette TRACE 370 <- [1,2,null,{}]
05:07:24 INFO - 1499256444726 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:07:24 INFO - 1499256444736 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"536cdd21-434e-47e4-95d9-d2718f73f013","ELEMENT":"536cdd21-434e-47e4-95d9-d2718f73f013"}}]
05:07:24 INFO - 1499256444738 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"536cdd21-434e-47e4-95d9-d2718f73f013"}]
05:07:25 INFO - 1499256444993 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:07:25 INFO - 1499256444994 Marionette TRACE 370 <- [1,4,null,{}]
05:07:25 INFO - 1499256444995 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"536cdd21-434e-47e4-95d9-d2718f73f013","name":"selected"}]
05:07:25 INFO - 1499256445000 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:07:25 INFO - 1499256445002 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}]
05:07:25 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499256445005 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:07:25 INFO - 1499256445007 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:07:25 INFO - 1499256445008 Marionette TRACE 370 <- [1,7,null,{}]
05:07:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 387ms
05:07:25 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:07:25 INFO - 1499256445011 Marionette DEBUG Closed connection 370
05:07:25 INFO - 1499256445012 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49888
05:07:25 INFO - 1499256445013 Marionette DEBUG Closed connection 371
05:07:25 INFO - 1499256445014 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49889
05:07:25 INFO - 1499256445015 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:25 INFO - 1499256445016 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:25 INFO - 1499256445019 Marionette DEBUG Register listener.js for window 12
05:07:25 INFO - 1499256445023 Marionette TRACE 372 <- [1,1,null,{"sessionId":"f002919d-3831-4199-9591-38345da3c47f","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:25 INFO - 1499256445024 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:07:25 INFO - 1499256445025 Marionette TRACE 372 <- [1,2,null,{}]
05:07:25 INFO - 1499256445026 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:07:25 INFO - 1499256445027 Marionette TRACE 372 <- [1,3,null,{}]
05:07:25 INFO - 1499256445028 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:07:25 INFO - 1499256445028 Marionette TRACE 372 <- [1,4,null,{}]
05:07:25 INFO - 1499256445030 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}]
05:07:25 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499256445034 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:07:25 INFO - 1499256445035 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:07:25 INFO - 1499256445036 Marionette TRACE 372 <- [1,6,null,{}]
05:07:25 INFO - 1499256445039 Marionette DEBUG Closed connection 372
05:07:25 INFO - 1499256445040 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49890
05:07:25 INFO - 1499256445041 Marionette DEBUG Closed connection 373
05:07:25 INFO - 1499256445042 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49891
05:07:25 INFO - 1499256445043 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:25 INFO - 1499256445043 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:25 INFO - 1499256445046 Marionette DEBUG Register listener.js for window 12
05:07:25 INFO - 1499256445051 Marionette TRACE 374 <- [1,1,null,{"sessionId":"9ec8d1f7-1bb6-4a60-863c-6e6732b4a8d3","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:25 INFO - 1499256445052 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:07:25 INFO - 1499256445054 Marionette TRACE 374 <- [1,2,null,{}]
05:07:25 INFO - 1499256445057 Marionette DEBUG Closed connection 374
05:07:25 INFO - 1499256445057 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49892
05:07:25 INFO - 1499256445058 Marionette DEBUG Closed connection 375
05:07:25 INFO - 1499256445059 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49893
05:07:25 INFO - 1499256445060 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:07:25 INFO - 1499256445061 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:25 INFO - 1499256445064 Marionette DEBUG Register listener.js for window 12
05:07:25 INFO - 1499256445071 Marionette TRACE 376 <- [1,1,null,{"sessionId":"cd846f54-f574-49bc-8bb7-28ebdfb4b0ec","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:25 INFO - 1499256445122 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"}]
05:07:25 INFO - 1499256445124 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"
05:07:25 INFO - 1499256445127 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"
05:07:25 INFO - 1499256445127 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"
05:07:25 INFO - 1499256445151 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"
05:07:25 INFO - 1499256445156 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"
05:07:25 INFO - 1499256445160 Marionette TRACE 376 <- [1,2,null,{}]
05:07:25 INFO - 1499256445169 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:07:25 INFO - 1499256445173 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b173aeb4-e258-4033-89e6-97032c1a9f67","ELEMENT":"b173aeb4-e258-4033-89e6-97032c1a9f67"}}]
05:07:25 INFO - 1499256445179 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b173aeb4-e258-4033-89e6-97032c1a9f67"}]
05:07:25 INFO - 1499256445477 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:07:25 INFO - 1499256445479 Marionette TRACE 376 <- [1,4,null,{}]
05:07:25 INFO - 1499256445482 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}]
05:07:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499256445488 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:07:25 INFO - 1499256445490 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:07:25 INFO - 1499256445494 Marionette TRACE 376 <- [1,6,null,{}]
05:07:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 487ms
05:07:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:07:25 INFO - 1499256445541 Marionette DEBUG Closed connection 376
05:07:25 INFO - 1499256445543 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49894
05:07:25 INFO - 1499256445553 Marionette DEBUG Closed connection 377
05:07:25 INFO - 1499256445554 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49895
05:07:25 INFO - 1499256445561 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:25 INFO - 1499256445565 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:25 INFO - 1499256445569 Marionette DEBUG Register listener.js for window 12
05:07:25 INFO - 1499256445577 Marionette TRACE 378 <- [1,1,null,{"sessionId":"46bd14ba-c5f2-45c5-9748-899c1fd7998f","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:25 INFO - 1499256445579 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:07:25 INFO - 1499256445581 Marionette TRACE 378 <- [1,2,null,{}]
05:07:25 INFO - 1499256445583 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:07:25 INFO - 1499256445584 Marionette TRACE 378 <- [1,3,null,{}]
05:07:25 INFO - 1499256445585 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:07:25 INFO - 1499256445586 Marionette TRACE 378 <- [1,4,null,{}]
05:07:25 INFO - 1499256445588 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}]
05:07:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499256445593 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:07:25 INFO - 1499256445595 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:07:25 INFO - 1499256445597 Marionette TRACE 378 <- [1,6,null,{}]
05:07:25 INFO - 1499256445603 Marionette DEBUG Closed connection 378
05:07:25 INFO - 1499256445604 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49896
05:07:25 INFO - 1499256445605 Marionette DEBUG Closed connection 379
05:07:25 INFO - 1499256445606 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49897
05:07:25 INFO - 1499256445608 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:25 INFO - 1499256445610 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:25 INFO - 1499256445613 Marionette DEBUG Register listener.js for window 12
05:07:25 INFO - 1499256445619 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0258383a-4f15-40e9-9904-dcc3c0111ab7","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:25 INFO - 1499256445621 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:07:25 INFO - 1499256445623 Marionette TRACE 380 <- [1,2,null,{}]
05:07:25 INFO - 1499256445626 Marionette DEBUG Closed connection 380
05:07:25 INFO - 1499256445627 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49898
05:07:25 INFO - 1499256445628 Marionette DEBUG Closed connection 381
05:07:25 INFO - 1499256445629 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49899
05:07:25 INFO - 1499256445632 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:07:25 INFO - 1499256445634 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:25 INFO - 1499256445637 Marionette DEBUG Register listener.js for window 12
05:07:25 INFO - 1499256445643 Marionette TRACE 382 <- [1,1,null,{"sessionId":"93a79ae8-f0ba-423d-8a8f-2decdb13a79d","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:25 INFO - 1499256445645 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"}]
05:07:25 INFO - 1499256445647 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"
05:07:25 INFO - 1499256445653 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"
05:07:25 INFO - 1499256445653 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"
05:07:25 INFO - 1499256445690 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"
05:07:25 INFO - 1499256445708 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"
05:07:25 INFO - 1499256445732 Marionette TRACE 382 <- [1,2,null,{}]
05:07:25 INFO - 1499256445735 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:07:25 INFO - 1499256445740 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d9f9ddc8-71d7-4bd5-9b17-9d285cdcf75c","ELEMENT":"d9f9ddc8-71d7-4bd5-9b17-9d285cdcf75c"}}]
05:07:25 INFO - 1499256445741 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d9f9ddc8-71d7-4bd5-9b17-9d285cdcf75c"}]
05:07:26 INFO - 1499256445997 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:07:26 INFO - 1499256445998 Marionette TRACE 382 <- [1,4,null,{}]
05:07:26 INFO - 1499256446000 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}]
05:07:26 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499256446004 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:07:26 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 562ms
05:07:26 INFO - TEST-START | test_click.py TestClick.test_input_file
05:07:26 INFO - 1499256446055 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:07:26 INFO - 1499256446057 Marionette TRACE 382 <- [1,6,null,{}]
05:07:26 INFO - 1499256446073 Marionette DEBUG Closed connection 382
05:07:26 INFO - 1499256446074 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49900
05:07:26 INFO - 1499256446090 Marionette DEBUG Closed connection 383
05:07:26 INFO - 1499256446091 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49901
05:07:26 INFO - 1499256446092 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:26 INFO - 1499256446093 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446097 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446102 Marionette TRACE 384 <- [1,1,null,{"sessionId":"6b242149-1811-46de-9162-137d895b13a9","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446105 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:07:26 INFO - 1499256446106 Marionette TRACE 384 <- [1,2,null,{}]
05:07:26 INFO - 1499256446107 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:07:26 INFO - 1499256446108 Marionette TRACE 384 <- [1,3,null,{}]
05:07:26 INFO - 1499256446109 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:07:26 INFO - 1499256446110 Marionette TRACE 384 <- [1,4,null,{}]
05:07:26 INFO - 1499256446112 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}]
05:07:26 INFO - TEST-START: test_click.py TestClick.test_input_file1499256446117 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:07:26 INFO - 1499256446118 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:07:26 INFO - 1499256446120 Marionette TRACE 384 <- [1,6,null,{}]
05:07:26 INFO - 1499256446123 Marionette DEBUG Closed connection 384
05:07:26 INFO - 1499256446124 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49902
05:07:26 INFO - 1499256446125 Marionette DEBUG Closed connection 385
05:07:26 INFO - 1499256446125 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49903
05:07:26 INFO - 1499256446127 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:26 INFO - 1499256446128 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446131 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446136 Marionette TRACE 386 <- [1,1,null,{"sessionId":"a511ac7c-8dca-4749-b6cb-9129902748d9","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446138 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:07:26 INFO - 1499256446141 Marionette TRACE 386 <- [1,2,null,{}]
05:07:26 INFO - 1499256446144 Marionette DEBUG Closed connection 386
05:07:26 INFO - 1499256446146 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49904
05:07:26 INFO - 1499256446147 Marionette DEBUG Closed connection 387
05:07:26 INFO - 1499256446148 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49905
05:07:26 INFO - 1499256446150 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:07:26 INFO - 1499256446152 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446155 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446160 Marionette TRACE 388 <- [1,1,null,{"sessionId":"589ef28b-41a6-453d-81a9-82192d120f59","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446164 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:07:26 INFO - 1499256446166 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"
05:07:26 INFO - 1499256446170 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"
05:07:26 INFO - 1499256446171 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"
05:07:26 INFO - 1499256446196 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:07:26 INFO - 1499256446206 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:07:26 INFO - 1499256446211 Marionette TRACE 388 <- [1,2,null,{}]
05:07:26 INFO - 1499256446221 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:07:26 INFO - 1499256446225 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"936b0634-b6e6-42a0-b866-272de8e7f619","ELEMENT":"936b0634-b6e6-42a0-b866-272de8e7f619"}}]
05:07:26 INFO - 1499256446226 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"936b0634-b6e6-42a0-b866-272de8e7f619"}]
05:07:26 INFO - 1499256446258 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]
05:07:26 INFO - 1499256446260 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}]
05:07:26 INFO - TEST-END: test_click.py TestClick.test_input_file1499256446265 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:07:26 INFO - 1499256446266 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:07:26 INFO - 1499256446267 Marionette TRACE 388 <- [1,6,null,{}]
05:07:26 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 210ms
05:07:26 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:07:26 INFO - 1499256446270 Marionette DEBUG Closed connection 388
05:07:26 INFO - 1499256446271 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49906
05:07:26 INFO - 1499256446272 Marionette DEBUG Closed connection 389
05:07:26 INFO - 1499256446273 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49907
05:07:26 INFO - 1499256446274 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:26 INFO - 1499256446275 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446278 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446283 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7c2a6605-1116-4d0f-92a0-88dd0cae9776","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446284 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:07:26 INFO - 1499256446285 Marionette TRACE 390 <- [1,2,null,{}]
05:07:26 INFO - 1499256446286 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:07:26 INFO - 1499256446286 Marionette TRACE 390 <- [1,3,null,{}]
05:07:26 INFO - 1499256446287 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:07:26 INFO - 1499256446288 Marionette TRACE 390 <- [1,4,null,{}]
05:07:26 INFO - 1499256446290 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}]
05:07:26 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499256446294 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:07:26 INFO - 1499256446296 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:07:26 INFO - 1499256446297 Marionette TRACE 390 <- [1,6,null,{}]
05:07:26 INFO - 1499256446300 Marionette DEBUG Closed connection 390
05:07:26 INFO - 1499256446301 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49908
05:07:26 INFO - 1499256446302 Marionette DEBUG Closed connection 391
05:07:26 INFO - 1499256446303 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49909
05:07:26 INFO - 1499256446304 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:26 INFO - 1499256446305 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446308 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446312 Marionette TRACE 392 <- [1,1,null,{"sessionId":"5c58fa29-2d3f-4c9c-a085-12321cc94ffb","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446313 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:07:26 INFO - 1499256446315 Marionette TRACE 392 <- [1,2,null,{}]
05:07:26 INFO - 1499256446317 Marionette DEBUG Closed connection 392
05:07:26 INFO - 1499256446318 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49910
05:07:26 INFO - 1499256446319 Marionette DEBUG Closed connection 393
05:07:26 INFO - 1499256446321 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49911
05:07:26 INFO - 1499256446322 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:07:26 INFO - 1499256446323 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446326 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446330 Marionette TRACE 394 <- [1,1,null,{"sessionId":"b814a29f-5574-4302-9f95-ef3b197f25db","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446332 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"}]
05:07:26 INFO - 1499256446334 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:07:26 INFO - 1499256446337 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:07:26 INFO - 1499256446337 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:07:26 INFO - 1499256446367 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"
05:07:26 INFO - 1499256446370 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"
05:07:26 INFO - 1499256446375 Marionette TRACE 394 <- [1,2,null,{}]
05:07:26 INFO - 1499256446376 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:07:26 INFO - 1499256446387 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c44efbd1-f27f-4dd3-b46a-ef6da39b64d4","ELEMENT":"c44efbd1-f27f-4dd3-b46a-ef6da39b64d4"}}]
05:07:26 INFO - 1499256446390 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:07:26 INFO - 1499256446393 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1b6312b5-cf2a-4530-af95-dfe9f47d8937","ELEMENT":"1b6312b5-cf2a-4530-af95-dfe9f47d8937"}}]
05:07:26 INFO - 1499256446394 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"c44efbd1-f27f-4dd3-b46a-ef6da39b64d4"}]
05:07:26 INFO - 1499256446640 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:07:26 INFO - 1499256446641 Marionette TRACE 394 <- [1,5,null,{}]
05:07:26 INFO - 1499256446642 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"1b6312b5-cf2a-4530-af95-dfe9f47d8937"}]
05:07:26 INFO - 1499256446663 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]
05:07:26 INFO - 1499256446665 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:07:26 INFO - 1499256446669 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:07:26 INFO - 1499256446671 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}]
05:07:26 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499256446675 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:07:26 INFO - 1499256446676 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:07:26 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 410ms
05:07:26 INFO - 1499256446678 Marionette TRACE 394 <- [1,9,null,{}]
05:07:26 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:07:26 INFO - 1499256446680 Marionette DEBUG Closed connection 394
05:07:26 INFO - 1499256446682 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49912
05:07:26 INFO - 1499256446682 Marionette DEBUG Closed connection 395
05:07:26 INFO - 1499256446683 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49913
05:07:26 INFO - 1499256446684 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:26 INFO - 1499256446685 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446688 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446700 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ffeeed71-3702-4394-b701-fa5d807db3e9","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446704 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:07:26 INFO - 1499256446704 Marionette TRACE 396 <- [1,2,null,{}]
05:07:26 INFO - 1499256446706 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:07:26 INFO - 1499256446706 Marionette TRACE 396 <- [1,3,null,{}]
05:07:26 INFO - 1499256446707 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:07:26 INFO - 1499256446708 Marionette TRACE 396 <- [1,4,null,{}]
05:07:26 INFO - 1499256446710 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}]
05:07:26 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499256446714 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:07:26 INFO - 1499256446715 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:07:26 INFO - 1499256446717 Marionette TRACE 396 <- [1,6,null,{}]
05:07:26 INFO - 1499256446719 Marionette DEBUG Closed connection 396
05:07:26 INFO - 1499256446721 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49914
05:07:26 INFO - 1499256446722 Marionette DEBUG Closed connection 397
05:07:26 INFO - 1499256446723 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49915
05:07:26 INFO - 1499256446724 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:07:26 INFO - 1499256446726 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446731 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446736 Marionette TRACE 398 <- [1,1,null,{"sessionId":"c7d4395e-46ea-41a0-af6c-f25c165e499c","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446737 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:07:26 INFO - 1499256446739 Marionette TRACE 398 <- [1,2,null,{}]
05:07:26 INFO - 1499256446742 Marionette DEBUG Closed connection 398
05:07:26 INFO - 1499256446742 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49916
05:07:26 INFO - 1499256446743 Marionette DEBUG Closed connection 399
05:07:26 INFO - 1499256446744 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49917
05:07:26 INFO - 1499256446746 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:07:26 INFO - 1499256446747 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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}
05:07:26 INFO - 1499256446750 Marionette DEBUG Register listener.js for window 12
05:07:26 INFO - 1499256446754 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b8876b98-d70f-46e4-a94a-55486f995917","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":1608,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxzyfxx.mozrunner","moz:accessibilityChecks":false}}]
05:07:26 INFO - 1499256446756 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"}]
05:07:26 INFO - 1499256446758 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"
05:07:26 INFO - 1499256446762 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"
05:07:26 INFO - 1499256446763 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"
05:07:26 INFO - 1499256446789 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"
05:07:26 INFO - 1499256446792 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"
05:07:26 INFO - 1499256446804 Marionette TRACE 400 <- [1,2,null,{}]
05:07:26 INFO - 1499256446815 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:07:26 INFO - 1499256446819 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9ba4b05d-5931-4285-b433-0030d2f543f3","ELEMENT":"9ba4b05d-5931-4285-b433-0030d2f543f3"}}]
05:07:26 INFO - 1499256446820 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9ba4b05d-5931-4285-b433-0030d2f543f3"}]
05:07:26 INFO - 1499256446846 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:07:26 INFO - 1499256446847 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9ba4b05d-5931-4285-b433-0030d2f543f3"}]
05:07:26 INFO - 1499256446872 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element