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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
20:00:14 INFO - 1501038014131 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}]
20:00:14 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501038014135 Marionette TRACE 352 <- [1,5,null,{"value":null}]
20:00:14 INFO - 1501038014136 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
20:00:14 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 135ms
20:00:14 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
20:00:14 INFO - 1501038014137 Marionette TRACE 352 <- [1,6,null,{}]
20:00:14 INFO - 1501038014139 Marionette DEBUG Closed connection 352
20:00:14 INFO - 1501038014140 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50823
20:00:14 INFO - 1501038014141 Marionette DEBUG Closed connection 353
20:00:14 INFO - 1501038014141 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50824
20:00:14 INFO - 1501038014143 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:14 INFO - 1501038014143 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014146 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014149 Marionette TRACE 354 <- [1,1,null,{"sessionId":"d3207ffc-4dde-49f8-8e2b-f5de4e9a1ed8","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014151 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
20:00:14 INFO - 1501038014151 Marionette TRACE 354 <- [1,2,null,{}]
20:00:14 INFO - 1501038014152 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:00:14 INFO - 1501038014153 Marionette TRACE 354 <- [1,3,null,{}]
20:00:14 INFO - 1501038014154 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
20:00:14 INFO - 1501038014154 Marionette TRACE 354 <- [1,4,null,{}]
20:00:14 INFO - 1501038014156 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}]
20:00:14 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501038014160 Marionette TRACE 354 <- [1,5,null,{"value":null}]
20:00:14 INFO - 1501038014161 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
20:00:14 INFO - 1501038014163 Marionette TRACE 354 <- [1,6,null,{}]
20:00:14 INFO - 1501038014165 Marionette DEBUG Closed connection 354
20:00:14 INFO - 1501038014165 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50825
20:00:14 INFO - 1501038014166 Marionette DEBUG Closed connection 355
20:00:14 INFO - 1501038014167 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50826
20:00:14 INFO - 1501038014168 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:14 INFO - 1501038014169 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014171 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014174 Marionette TRACE 356 <- [1,1,null,{"sessionId":"0a7f34b8-77f3-45a8-a2d1-f59c899673c2","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014175 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
20:00:14 INFO - 1501038014177 Marionette TRACE 356 <- [1,2,null,{}]
20:00:14 INFO - 1501038014179 Marionette DEBUG Closed connection 356
20:00:14 INFO - 1501038014180 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50827
20:00:14 INFO - 1501038014180 Marionette DEBUG Closed connection 357
20:00:14 INFO - 1501038014181 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50828
20:00:14 INFO - 1501038014182 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:00:14 INFO - 1501038014183 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014185 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014189 Marionette TRACE 358 <- [1,1,null,{"sessionId":"56024104-f911-44b7-8734-4ebc7f792c74","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014190 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:50380/clicks.html"}]
20:00:14 INFO - 1501038014192 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50380/hidden.html"
20:00:14 INFO - 1501038014202 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50380/hidden.html"
20:00:14 INFO - 1501038014202 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50380/hidden.html"
20:00:14 INFO - 1501038014210 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50380/clicks.html"
20:00:14 INFO - 1501038014215 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50380/clicks.html"
20:00:14 INFO - 1501038014227 Marionette TRACE 358 <- [1,2,null,{}]
20:00:14 INFO - 1501038014241 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
20:00:14 INFO - 1501038014244 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f56fc499-0b63-4a78-aab2-00e2d2aa639b","ELEMENT":"f56fc499-0b63-4a78-aab2-00e2d2aa639b"}}]
20:00:14 INFO - 1501038014245 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f56fc499-0b63-4a78-aab2-00e2d2aa639b"}]
20:00:14 INFO - 1501038014289 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50380/clicks.html"
20:00:14 INFO - 1501038014302 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50380/clicks.html"
20:00:14 INFO - 1501038014303 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50380/clicks.html"
20:00:14 INFO - 1501038014312 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50380/test.html"
20:00:14 INFO - 1501038014319 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50380/test.html"
20:00:14 INFO - 1501038014323 Marionette TRACE 358 <- [1,4,null,{}]
20:00:14 INFO - 1501038014328 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
20:00:14 INFO - 1501038014335 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4dd3077f-02ad-48f6-a3a4-c05ef5217abf","ELEMENT":"4dd3077f-02ad-48f6-a3a4-c05ef5217abf"}}]
20:00:14 INFO - 1501038014337 Marionette TRACE 358 -> [0,6,"getTitle",{}]
20:00:14 INFO - 1501038014338 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
20:00:14 INFO - 1501038014340 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}]
20:00:14 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501038014343 Marionette TRACE 358 <- [1,7,null,{"value":null}]
20:00:14 INFO - 1501038014344 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
20:00:14 INFO - 1501038014346 Marionette TRACE 358 <- [1,8,null,{}]
20:00:14 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 208ms
20:00:14 INFO - TEST-START | test_click.py TestClick.test_container_element
20:00:14 INFO - 1501038014348 Marionette DEBUG Closed connection 358
20:00:14 INFO - 1501038014349 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50831
20:00:14 INFO - 1501038014350 Marionette DEBUG Closed connection 359
20:00:14 INFO - 1501038014351 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50832
20:00:14 INFO - 1501038014352 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:14 INFO - 1501038014353 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014355 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014360 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d951348b-d0d2-4f28-a8dc-12423c927f6a","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014361 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
20:00:14 INFO - 1501038014362 Marionette TRACE 360 <- [1,2,null,{}]
20:00:14 INFO - 1501038014363 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:00:14 INFO - 1501038014363 Marionette TRACE 360 <- [1,3,null,{}]
20:00:14 INFO - 1501038014364 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
20:00:14 INFO - 1501038014365 Marionette TRACE 360 <- [1,4,null,{}]
20:00:14 INFO - 1501038014367 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}]
20:00:14 INFO - TEST-START: test_click.py TestClick.test_container_element1501038014370 Marionette TRACE 360 <- [1,5,null,{"value":null}]
20:00:14 INFO - 1501038014371 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
20:00:14 INFO - 1501038014373 Marionette TRACE 360 <- [1,6,null,{}]
20:00:14 INFO - 1501038014375 Marionette DEBUG Closed connection 360
20:00:14 INFO - 1501038014376 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50833
20:00:14 INFO - 1501038014377 Marionette DEBUG Closed connection 361
20:00:14 INFO - 1501038014378 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50834
20:00:14 INFO - 1501038014379 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:14 INFO - 1501038014379 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014382 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014385 Marionette TRACE 362 <- [1,1,null,{"sessionId":"cf8b99c8-d52b-42b8-8c58-adb5ed285a0e","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014386 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
20:00:14 INFO - 1501038014388 Marionette TRACE 362 <- [1,2,null,{}]
20:00:14 INFO - 1501038014390 Marionette DEBUG Closed connection 362
20:00:14 INFO - 1501038014390 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50835
20:00:14 INFO - 1501038014391 Marionette DEBUG Closed connection 363
20:00:14 INFO - 1501038014392 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50836
20:00:14 INFO - 1501038014394 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:00:14 INFO - 1501038014394 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014396 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014400 Marionette TRACE 364 <- [1,1,null,{"sessionId":"33ff36d7-d4a1-4210-9718-a5daa3205178","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014401 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"}]
20:00:14 INFO - 1501038014404 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50380/test.html"
20:00:14 INFO - 1501038014406 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50380/test.html"
20:00:14 INFO - 1501038014407 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50380/test.html"
20:00:14 INFO - 1501038014419 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:14 INFO - 1501038014420 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:14 INFO - 1501038014432 Marionette TRACE 364 <- [1,2,null,{}]
20:00:14 INFO - 1501038014441 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:00:14 INFO - 1501038014444 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ba56e5fd-0c14-41fc-b418-17db5612a0ce","ELEMENT":"ba56e5fd-0c14-41fc-b418-17db5612a0ce"}}]
20:00:14 INFO - 1501038014446 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"ba56e5fd-0c14-41fc-b418-17db5612a0ce"}]
20:00:14 INFO - 1501038014701 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:00:14 INFO - 1501038014702 Marionette TRACE 364 <- [1,4,null,{}]
20:00:14 INFO - 1501038014703 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"ba56e5fd-0c14-41fc-b418-17db5612a0ce","name":"selected"}]
20:00:14 INFO - 1501038014708 Marionette TRACE 364 <- [1,5,null,{"value":true}]
20:00:14 INFO - 1501038014710 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}]
20:00:14 INFO - TEST-END: test_click.py TestClick.test_container_element1501038014714 Marionette TRACE 364 <- [1,6,null,{"value":null}]
20:00:14 INFO - 1501038014715 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
20:00:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 370ms
20:00:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
20:00:14 INFO - 1501038014717 Marionette TRACE 364 <- [1,7,null,{}]
20:00:14 INFO - 1501038014718 Marionette DEBUG Closed connection 364
20:00:14 INFO - 1501038014719 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50837
20:00:14 INFO - 1501038014720 Marionette DEBUG Closed connection 365
20:00:14 INFO - 1501038014721 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50838
20:00:14 INFO - 1501038014722 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:14 INFO - 1501038014722 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014725 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014728 Marionette TRACE 366 <- [1,1,null,{"sessionId":"7f88478e-1427-4838-94a6-2b7a946e35d8","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014730 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
20:00:14 INFO - 1501038014730 Marionette TRACE 366 <- [1,2,null,{}]
20:00:14 INFO - 1501038014731 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:00:14 INFO - 1501038014731 Marionette TRACE 366 <- [1,3,null,{}]
20:00:14 INFO - 1501038014732 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
20:00:14 INFO - 1501038014733 Marionette TRACE 366 <- [1,4,null,{}]
20:00:14 INFO - 1501038014735 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}]
20:00:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501038014738 Marionette TRACE 366 <- [1,5,null,{"value":null}]
20:00:14 INFO - 1501038014739 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
20:00:14 INFO - 1501038014740 Marionette TRACE 366 <- [1,6,null,{}]
20:00:14 INFO - 1501038014742 Marionette DEBUG Closed connection 366
20:00:14 INFO - 1501038014743 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50839
20:00:14 INFO - 1501038014743 Marionette DEBUG Closed connection 367
20:00:14 INFO - 1501038014744 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50840
20:00:14 INFO - 1501038014745 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:14 INFO - 1501038014746 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014748 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014751 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c1a4147c-de10-4fa9-9927-7ac6e6c5d403","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014753 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
20:00:14 INFO - 1501038014754 Marionette TRACE 368 <- [1,2,null,{}]
20:00:14 INFO - 1501038014756 Marionette DEBUG Closed connection 368
20:00:14 INFO - 1501038014756 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50841
20:00:14 INFO - 1501038014757 Marionette DEBUG Closed connection 369
20:00:14 INFO - 1501038014758 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50842
20:00:14 INFO - 1501038014759 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:00:14 INFO - 1501038014760 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:14 INFO - 1501038014762 Marionette DEBUG Register listener.js for window 4294967297
20:00:14 INFO - 1501038014766 Marionette TRACE 370 <- [1,1,null,{"sessionId":"76f50e09-5fd8-48aa-bac5-59b16e660f4c","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:14 INFO - 1501038014767 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"}]
20:00:14 INFO - 1501038014769 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:14 INFO - 1501038014771 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:14 INFO - 1501038014772 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:14 INFO - 1501038014779 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:14 INFO - 1501038014782 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:14 INFO - 1501038014792 Marionette TRACE 370 <- [1,2,null,{}]
20:00:14 INFO - 1501038014801 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
20:00:14 INFO - 1501038014805 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3d0af08a-0932-434c-bd08-e23358fe7c44","ELEMENT":"3d0af08a-0932-434c-bd08-e23358fe7c44"}}]
20:00:14 INFO - 1501038014806 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"3d0af08a-0932-434c-bd08-e23358fe7c44"}]
20:00:15 INFO - 1501038015052 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:00:15 INFO - 1501038015053 Marionette TRACE 370 <- [1,4,null,{}]
20:00:15 INFO - 1501038015054 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"3d0af08a-0932-434c-bd08-e23358fe7c44","name":"selected"}]
20:00:15 INFO - 1501038015059 Marionette TRACE 370 <- [1,5,null,{"value":true}]
20:00:15 INFO - 1501038015061 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}]
20:00:15 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501038015064 Marionette TRACE 370 <- [1,6,null,{"value":null}]
20:00:15 INFO - 1501038015065 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
20:00:15 INFO - 1501038015066 Marionette TRACE 370 <- [1,7,null,{}]
20:00:15 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 350ms
20:00:15 INFO - TEST-START | test_click.py TestClick.test_css_transforms
20:00:15 INFO - 1501038015068 Marionette DEBUG Closed connection 370
20:00:15 INFO - 1501038015069 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50843
20:00:15 INFO - 1501038015070 Marionette DEBUG Closed connection 371
20:00:15 INFO - 1501038015071 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50844
20:00:15 INFO - 1501038015072 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:15 INFO - 1501038015072 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015075 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015086 Marionette TRACE 372 <- [1,1,null,{"sessionId":"142abfd3-efde-4472-857b-07e141ff1674","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015087 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
20:00:15 INFO - 1501038015088 Marionette TRACE 372 <- [1,2,null,{}]
20:00:15 INFO - 1501038015089 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:00:15 INFO - 1501038015089 Marionette TRACE 372 <- [1,3,null,{}]
20:00:15 INFO - 1501038015090 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
20:00:15 INFO - 1501038015091 Marionette TRACE 372 <- [1,4,null,{}]
20:00:15 INFO - 1501038015093 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}]
20:00:15 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501038015096 Marionette TRACE 372 <- [1,5,null,{"value":null}]
20:00:15 INFO - 1501038015098 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
20:00:15 INFO - 1501038015100 Marionette TRACE 372 <- [1,6,null,{}]
20:00:15 INFO - 1501038015101 Marionette DEBUG Closed connection 372
20:00:15 INFO - 1501038015102 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50845
20:00:15 INFO - 1501038015103 Marionette DEBUG Closed connection 373
20:00:15 INFO - 1501038015104 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50846
20:00:15 INFO - 1501038015105 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:15 INFO - 1501038015105 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015107 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015111 Marionette TRACE 374 <- [1,1,null,{"sessionId":"69c25e86-dbdb-4044-96a0-8a9133a7bb01","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015112 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
20:00:15 INFO - 1501038015113 Marionette TRACE 374 <- [1,2,null,{}]
20:00:15 INFO - 1501038015117 Marionette DEBUG Closed connection 374
20:00:15 INFO - 1501038015117 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50847
20:00:15 INFO - 1501038015118 Marionette DEBUG Closed connection 375
20:00:15 INFO - 1501038015119 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50848
20:00:15 INFO - 1501038015120 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:00:15 INFO - 1501038015121 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015123 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015126 Marionette TRACE 376 <- [1,1,null,{"sessionId":"65ffe94f-b394-426c-80f6-8d191dfe306a","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015128 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"}]
20:00:15 INFO - 1501038015130 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:15 INFO - 1501038015134 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:15 INFO - 1501038015134 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:15 INFO - 1501038015141 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:00:15 INFO - 1501038015143 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:00:15 INFO - 1501038015148 Marionette TRACE 376 <- [1,2,null,{}]
20:00:15 INFO - 1501038015159 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
20:00:15 INFO - 1501038015162 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d6cfe5d2-6fdc-42a5-967d-83de449d0f94","ELEMENT":"d6cfe5d2-6fdc-42a5-967d-83de449d0f94"}}]
20:00:15 INFO - 1501038015163 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d6cfe5d2-6fdc-42a5-967d-83de449d0f94"}]
20:00:15 INFO - 1501038015404 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:00:15 INFO - 1501038015405 Marionette TRACE 376 <- [1,4,null,{}]
20:00:15 INFO - 1501038015407 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}]
20:00:15 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501038015410 Marionette TRACE 376 <- [1,5,null,{"value":null}]
20:00:15 INFO - 1501038015411 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
20:00:15 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
20:00:15 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
20:00:15 INFO - 1501038015413 Marionette TRACE 376 <- [1,6,null,{}]
20:00:15 INFO - 1501038015415 Marionette DEBUG Closed connection 376
20:00:15 INFO - 1501038015415 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50849
20:00:15 INFO - 1501038015416 Marionette DEBUG Closed connection 377
20:00:15 INFO - 1501038015417 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50850
20:00:15 INFO - 1501038015418 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:15 INFO - 1501038015419 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015421 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015424 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e4a9f0ba-9a22-4cc4-974e-e1ec327c95e8","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015426 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
20:00:15 INFO - 1501038015426 Marionette TRACE 378 <- [1,2,null,{}]
20:00:15 INFO - 1501038015427 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:00:15 INFO - 1501038015428 Marionette TRACE 378 <- [1,3,null,{}]
20:00:15 INFO - 1501038015429 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
20:00:15 INFO - 1501038015429 Marionette TRACE 378 <- [1,4,null,{}]
20:00:15 INFO - 1501038015431 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}]
20:00:15 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501038015434 Marionette TRACE 378 <- [1,5,null,{"value":null}]
20:00:15 INFO - 1501038015435 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
20:00:15 INFO - 1501038015436 Marionette TRACE 378 <- [1,6,null,{}]
20:00:15 INFO - 1501038015438 Marionette DEBUG Closed connection 378
20:00:15 INFO - 1501038015439 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50851
20:00:15 INFO - 1501038015440 Marionette DEBUG Closed connection 379
20:00:15 INFO - 1501038015440 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50852
20:00:15 INFO - 1501038015441 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:15 INFO - 1501038015442 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015444 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015448 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d82d6343-008e-4040-9a9a-b181dede4cd7","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015449 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
20:00:15 INFO - 1501038015450 Marionette TRACE 380 <- [1,2,null,{}]
20:00:15 INFO - 1501038015452 Marionette DEBUG Closed connection 380
20:00:15 INFO - 1501038015453 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50853
20:00:15 INFO - 1501038015454 Marionette DEBUG Closed connection 381
20:00:15 INFO - 1501038015454 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50854
20:00:15 INFO - 1501038015455 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:00:15 INFO - 1501038015456 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015458 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015462 Marionette TRACE 382 <- [1,1,null,{"sessionId":"f55b7950-daa4-48bc-a270-b58c9f3381c8","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015463 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"}]
20:00:15 INFO - 1501038015465 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:00:15 INFO - 1501038015467 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:00:15 INFO - 1501038015467 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
20:00:15 INFO - 1501038015474 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:15 INFO - 1501038015478 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:15 INFO - 1501038015487 Marionette TRACE 382 <- [1,2,null,{}]
20:00:15 INFO - 1501038015491 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
20:00:15 INFO - 1501038015495 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"450046d7-8fe8-4fe7-8cdc-01531524ef4a","ELEMENT":"450046d7-8fe8-4fe7-8cdc-01531524ef4a"}}]
20:00:15 INFO - 1501038015501 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"450046d7-8fe8-4fe7-8cdc-01531524ef4a"}]
20:00:15 INFO - 1501038015752 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:00:15 INFO - 1501038015753 Marionette TRACE 382 <- [1,4,null,{}]
20:00:15 INFO - 1501038015755 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}]
20:00:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501038015758 Marionette TRACE 382 <- [1,5,null,{"value":null}]
20:00:15 INFO - 1501038015759 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
20:00:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 347ms
20:00:15 INFO - TEST-START | test_click.py TestClick.test_input_file
20:00:15 INFO - 1501038015761 Marionette TRACE 382 <- [1,6,null,{}]
20:00:15 INFO - 1501038015763 Marionette DEBUG Closed connection 382
20:00:15 INFO - 1501038015764 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50855
20:00:15 INFO - 1501038015764 Marionette DEBUG Closed connection 383
20:00:15 INFO - 1501038015765 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50856
20:00:15 INFO - 1501038015767 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:15 INFO - 1501038015767 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015770 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015773 Marionette TRACE 384 <- [1,1,null,{"sessionId":"aeeee7f7-b031-41f1-8f59-c720fa132907","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015774 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
20:00:15 INFO - 1501038015775 Marionette TRACE 384 <- [1,2,null,{}]
20:00:15 INFO - 1501038015776 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:00:15 INFO - 1501038015777 Marionette TRACE 384 <- [1,3,null,{}]
20:00:15 INFO - 1501038015777 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
20:00:15 INFO - 1501038015778 Marionette TRACE 384 <- [1,4,null,{}]
20:00:15 INFO - 1501038015780 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}]
20:00:15 INFO - TEST-START: test_click.py TestClick.test_input_file1501038015783 Marionette TRACE 384 <- [1,5,null,{"value":null}]
20:00:15 INFO - 1501038015784 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
20:00:15 INFO - 1501038015785 Marionette TRACE 384 <- [1,6,null,{}]
20:00:15 INFO - 1501038015787 Marionette DEBUG Closed connection 384
20:00:15 INFO - 1501038015787 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50857
20:00:15 INFO - 1501038015788 Marionette DEBUG Closed connection 385
20:00:15 INFO - 1501038015789 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50858
20:00:15 INFO - 1501038015790 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:15 INFO - 1501038015791 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015793 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015796 Marionette TRACE 386 <- [1,1,null,{"sessionId":"82353bf3-c074-4bb8-9297-1e75226c8966","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015797 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
20:00:15 INFO - 1501038015799 Marionette TRACE 386 <- [1,2,null,{}]
20:00:15 INFO - 1501038015800 Marionette DEBUG Closed connection 386
20:00:15 INFO - 1501038015801 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50859
20:00:15 INFO - 1501038015802 Marionette DEBUG Closed connection 387
20:00:15 INFO - 1501038015803 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50860
20:00:15 INFO - 1501038015804 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:00:15 INFO - 1501038015805 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015807 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015810 Marionette TRACE 388 <- [1,1,null,{"sessionId":"6ac8a7bc-aabd-454b-b937-54f89c8b0107","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015812 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
20:00:15 INFO - 1501038015814 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:15 INFO - 1501038015816 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:15 INFO - 1501038015816 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
20:00:15 INFO - 1501038015823 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:00:15 INFO - 1501038015828 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:00:15 INFO - 1501038015830 Marionette TRACE 388 <- [1,2,null,{}]
20:00:15 INFO - 1501038015840 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
20:00:15 INFO - 1501038015843 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4a1f2392-482e-488a-823f-ab4a97298839","ELEMENT":"4a1f2392-482e-488a-823f-ab4a97298839"}}]
20:00:15 INFO - 1501038015844 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4a1f2392-482e-488a-823f-ab4a97298839"}]
20:00:15 INFO - 1501038015872 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
20:00:15 INFO - 1501038015874 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}]
20:00:15 INFO - TEST-END: test_click.py TestClick.test_input_file1501038015877 Marionette TRACE 388 <- [1,5,null,{"value":null}]
20:00:15 INFO - 1501038015878 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
20:00:15 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 118ms
20:00:15 INFO - TEST-START | test_click.py TestClick.test_obscured_element
20:00:15 INFO - 1501038015880 Marionette TRACE 388 <- [1,6,null,{}]
20:00:15 INFO - 1501038015882 Marionette DEBUG Closed connection 388
20:00:15 INFO - 1501038015883 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50861
20:00:15 INFO - 1501038015883 Marionette DEBUG Closed connection 389
20:00:15 INFO - 1501038015884 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50862
20:00:15 INFO - 1501038015885 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:15 INFO - 1501038015886 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015888 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015892 Marionette TRACE 390 <- [1,1,null,{"sessionId":"299a4908-4f30-47bb-97ad-3c5ceec255f2","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015893 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
20:00:15 INFO - 1501038015894 Marionette TRACE 390 <- [1,2,null,{}]
20:00:15 INFO - 1501038015894 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:00:15 INFO - 1501038015895 Marionette TRACE 390 <- [1,3,null,{}]
20:00:15 INFO - 1501038015896 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
20:00:15 INFO - 1501038015896 Marionette TRACE 390 <- [1,4,null,{}]
20:00:15 INFO - 1501038015898 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}]
20:00:15 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501038015901 Marionette TRACE 390 <- [1,5,null,{"value":null}]
20:00:15 INFO - 1501038015902 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
20:00:15 INFO - 1501038015904 Marionette TRACE 390 <- [1,6,null,{}]
20:00:15 INFO - 1501038015906 Marionette DEBUG Closed connection 390
20:00:15 INFO - 1501038015906 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50863
20:00:15 INFO - 1501038015907 Marionette DEBUG Closed connection 391
20:00:15 INFO - 1501038015908 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50864
20:00:15 INFO - 1501038015909 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:15 INFO - 1501038015910 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015912 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015916 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a4679b79-9536-441a-b0b8-166a07044187","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015917 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
20:00:15 INFO - 1501038015918 Marionette TRACE 392 <- [1,2,null,{}]
20:00:15 INFO - 1501038015920 Marionette DEBUG Closed connection 392
20:00:15 INFO - 1501038015921 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50865
20:00:15 INFO - 1501038015921 Marionette DEBUG Closed connection 393
20:00:15 INFO - 1501038015922 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50866
20:00:15 INFO - 1501038015923 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:00:15 INFO - 1501038015924 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:15 INFO - 1501038015926 Marionette DEBUG Register listener.js for window 4294967297
20:00:15 INFO - 1501038015929 Marionette TRACE 394 <- [1,1,null,{"sessionId":"97c3ef35-0100-4170-b1ed-3fbf6badb982","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:15 INFO - 1501038015931 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"}]
20:00:15 INFO - 1501038015933 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:00:15 INFO - 1501038015935 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:00:15 INFO - 1501038015935 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
20:00:15 INFO - 1501038015943 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:00:15 INFO - 1501038015946 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:00:15 INFO - 1501038015955 Marionette TRACE 394 <- [1,2,null,{}]
20:00:15 INFO - 1501038015959 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
20:00:15 INFO - 1501038015962 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f63a5d60-fa08-454e-a3f2-7363b14f3ca6","ELEMENT":"f63a5d60-fa08-454e-a3f2-7363b14f3ca6"}}]
20:00:15 INFO - 1501038015969 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
20:00:15 INFO - 1501038015972 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"748f74e3-8015-4958-9a4c-195731fc305f","ELEMENT":"748f74e3-8015-4958-9a4c-195731fc305f"}}]
20:00:15 INFO - 1501038015973 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"f63a5d60-fa08-454e-a3f2-7363b14f3ca6"}]
20:00:16 INFO - 1501038016216 Marionette DEBUG Canceled page load listener because no navigation has been detected
20:00:16 INFO - 1501038016217 Marionette TRACE 394 <- [1,5,null,{}]
20:00:16 INFO - 1501038016218 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"748f74e3-8015-4958-9a4c-195731fc305f"}]
20:00:16 INFO - 1501038016236 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
20:00:16 INFO - 1501038016238 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
20:00:16 INFO - 1501038016241 Marionette TRACE 394 <- [1,7,null,{"value":false}]
20:00:16 INFO - 1501038016243 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}]
20:00:16 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501038016246 Marionette TRACE 394 <- [1,8,null,{"value":null}]
20:00:16 INFO - 1501038016247 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
20:00:16 INFO - 1501038016248 Marionette TRACE 394 <- [1,9,null,{}]
20:00:16 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 368ms
20:00:16 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
20:00:16 INFO - 1501038016250 Marionette DEBUG Closed connection 394
20:00:16 INFO - 1501038016251 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50867
20:00:16 INFO - 1501038016252 Marionette DEBUG Closed connection 395
20:00:16 INFO - 1501038016253 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50868
20:00:16 INFO - 1501038016254 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:16 INFO - 1501038016254 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:16 INFO - 1501038016257 Marionette DEBUG Register listener.js for window 4294967297
20:00:16 INFO - 1501038016260 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a18836a9-da2e-4f6f-aee9-7c496fcf33bd","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:16 INFO - 1501038016262 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
20:00:16 INFO - 1501038016263 Marionette TRACE 396 <- [1,2,null,{}]
20:00:16 INFO - 1501038016264 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
20:00:16 INFO - 1501038016264 Marionette TRACE 396 <- [1,3,null,{}]
20:00:16 INFO - 1501038016265 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
20:00:16 INFO - 1501038016266 Marionette TRACE 396 <- [1,4,null,{}]
20:00:16 INFO - 1501038016267 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}]
20:00:16 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501038016271 Marionette TRACE 396 <- [1,5,null,{"value":null}]
20:00:16 INFO - 1501038016272 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
20:00:16 INFO - 1501038016273 Marionette TRACE 396 <- [1,6,null,{}]
20:00:16 INFO - 1501038016275 Marionette DEBUG Closed connection 396
20:00:16 INFO - 1501038016275 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50869
20:00:16 INFO - 1501038016276 Marionette DEBUG Closed connection 397
20:00:16 INFO - 1501038016277 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50870
20:00:16 INFO - 1501038016278 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
20:00:16 INFO - 1501038016279 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:16 INFO - 1501038016281 Marionette DEBUG Register listener.js for window 4294967297
20:00:16 INFO - 1501038016284 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a66212c0-ca21-4653-a94a-4733687c932d","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:16 INFO - 1501038016286 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
20:00:16 INFO - 1501038016287 Marionette TRACE 398 <- [1,2,null,{}]
20:00:16 INFO - 1501038016289 Marionette DEBUG Closed connection 398
20:00:16 INFO - 1501038016289 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50871
20:00:16 INFO - 1501038016290 Marionette DEBUG Closed connection 399
20:00:16 INFO - 1501038016291 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50872
20:00:16 INFO - 1501038016292 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
20:00:16 INFO - 1501038016293 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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}
20:00:16 INFO - 1501038016295 Marionette DEBUG Register listener.js for window 4294967297
20:00:16 INFO - 1501038016298 Marionette TRACE 400 <- [1,1,null,{"sessionId":"dc8f69e6-7d9d-4a47-84c1-6ba823ebe4c2","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":3540,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8nkvnr.mozrunner","moz:accessibilityChecks":false}}]
20:00:16 INFO - 1501038016300 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"}]
20:00:16 INFO - 1501038016302 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:00:16 INFO - 1501038016304 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:00:16 INFO - 1501038016304 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
20:00:16 INFO - 1501038016312 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
20:00:16 INFO - 1501038016314 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
20:00:16 INFO - 1501038016326 Marionette TRACE 400 <- [1,2,null,{}]
20:00:16 INFO - 1501038016336 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
20:00:16 INFO - 1501038016339 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b3d00f3d-3f7e-4884-8255-f0ee7e88f561","ELEMENT":"b3d00f3d-3f7e-4884-8255-f0ee7e88f561"}}]
20:00:16 INFO - 1501038016340 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b3d00f3d-3f7e-4884-8255-f0ee7e88f561"}]
20:00:16 INFO - 1501038016369 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
20:00:16 INFO - 1501038016371 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b3d00f3d-3f7e-4884-8255-f0ee7e88f561"}]
20:00:16 INFO - 1501038016388 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element