could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
05:20:00 INFO - 1500553200190 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:20:00 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500553200192 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:20:00 INFO - 1500553200193 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:20:00 INFO - 1500553200194 Marionette TRACE 352 <- [1,6,null,{}]
05:20:00 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 152ms
05:20:00 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:20:00 INFO - 1500553200196 Marionette DEBUG Closed connection 352
05:20:00 INFO - 1500553200197 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49752
05:20:00 INFO - 1500553200198 Marionette DEBUG Closed connection 353
05:20:00 INFO - 1500553200199 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49753
05:20:00 INFO - 1500553200199 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:00 INFO - 1500553200200 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200202 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200204 Marionette TRACE 354 <- [1,1,null,{"sessionId":"4d3c7497-d73a-4cd1-bec2-02d728e55792","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200206 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:20:00 INFO - 1500553200206 Marionette TRACE 354 <- [1,2,null,{}]
05:20:00 INFO - 1500553200207 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:00 INFO - 1500553200208 Marionette TRACE 354 <- [1,3,null,{}]
05:20:00 INFO - 1500553200208 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:00 INFO - 1500553200209 Marionette TRACE 354 <- [1,4,null,{}]
05:20:00 INFO - 1500553200210 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:20:00 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500553200212 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:20:00 INFO - 1500553200213 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:20:00 INFO - 1500553200215 Marionette TRACE 354 <- [1,6,null,{}]
05:20:00 INFO - 1500553200216 Marionette DEBUG Closed connection 354
05:20:00 INFO - 1500553200217 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49754
05:20:00 INFO - 1500553200218 Marionette DEBUG Closed connection 355
05:20:00 INFO - 1500553200218 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49755
05:20:00 INFO - 1500553200219 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:00 INFO - 1500553200220 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200221 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200224 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5a323a72-6579-4fd9-bf28-155e031189b1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200226 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:20:00 INFO - 1500553200227 Marionette TRACE 356 <- [1,2,null,{}]
05:20:00 INFO - 1500553200229 Marionette DEBUG Closed connection 356
05:20:00 INFO - 1500553200229 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49756
05:20:00 INFO - 1500553200230 Marionette DEBUG Closed connection 357
05:20:00 INFO - 1500553200230 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49757
05:20:00 INFO - 1500553200231 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:00 INFO - 1500553200232 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200234 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200236 Marionette TRACE 358 <- [1,1,null,{"sessionId":"086c6dba-3559-427c-9322-f73b0d76dfc9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200238 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49327/clicks.html"}]
05:20:00 INFO - 1500553200239 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/hidden.html"
05:20:00 INFO - 1500553200243 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/hidden.html"
05:20:00 INFO - 1500553200244 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/hidden.html"
05:20:00 INFO - 1500553200258 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49327/clicks.html"
05:20:00 INFO - 1500553200262 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49327/clicks.html"
05:20:00 INFO - 1500553200271 Marionette TRACE 358 <- [1,2,null,{}]
05:20:00 INFO - 1500553200272 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:20:00 INFO - 1500553200274 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf9017e6-c857-4d66-bab7-1922ef8df4b1","ELEMENT":"bf9017e6-c857-4d66-bab7-1922ef8df4b1"}}]
05:20:00 INFO - 1500553200275 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"bf9017e6-c857-4d66-bab7-1922ef8df4b1"}]
05:20:00 INFO - 1500553200320 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/clicks.html"
05:20:00 INFO - 1500553200324 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/clicks.html"
05:20:00 INFO - 1500553200325 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/clicks.html"
05:20:00 INFO - 1500553200342 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49327/test.html"
05:20:00 INFO - 1500553200345 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49327/test.html"
05:20:00 INFO - 1500553200347 Marionette TRACE 358 <- [1,4,null,{}]
05:20:00 INFO - 1500553200353 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:20:00 INFO - 1500553200355 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2c7817cf-5bc2-4539-b043-f66999fe633a","ELEMENT":"2c7817cf-5bc2-4539-b043-f66999fe633a"}}]
05:20:00 INFO - 1500553200356 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:20:00 INFO - 1500553200357 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:20:00 INFO - 1500553200358 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:20:00 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500553200360 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:20:00 INFO - 1500553200361 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:20:00 INFO - 1500553200362 Marionette TRACE 358 <- [1,8,null,{}]
05:20:00 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 166ms
05:20:00 INFO - TEST-START | test_click.py TestClick.test_container_element
05:20:00 INFO - 1500553200364 Marionette DEBUG Closed connection 358
05:20:00 INFO - 1500553200365 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49760
05:20:00 INFO - 1500553200366 Marionette DEBUG Closed connection 359
05:20:00 INFO - 1500553200366 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49761
05:20:00 INFO - 1500553200367 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:00 INFO - 1500553200367 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200369 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200372 Marionette TRACE 360 <- [1,1,null,{"sessionId":"74204293-a6df-4467-b37b-2b40f1dfea13","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200374 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:20:00 INFO - 1500553200374 Marionette TRACE 360 <- [1,2,null,{}]
05:20:00 INFO - 1500553200375 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:00 INFO - 1500553200375 Marionette TRACE 360 <- [1,3,null,{}]
05:20:00 INFO - 1500553200376 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:00 INFO - 1500553200376 Marionette TRACE 360 <- [1,4,null,{}]
05:20:00 INFO - 1500553200378 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:20:00 INFO - TEST-START: test_click.py TestClick.test_container_element1500553200381 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:20:00 INFO - 1500553200382 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:20:00 INFO - 1500553200383 Marionette TRACE 360 <- [1,6,null,{}]
05:20:00 INFO - 1500553200385 Marionette DEBUG Closed connection 360
05:20:00 INFO - 1500553200386 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49762
05:20:00 INFO - 1500553200386 Marionette DEBUG Closed connection 361
05:20:00 INFO - 1500553200387 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49763
05:20:00 INFO - 1500553200388 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:00 INFO - 1500553200389 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200391 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200393 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e2e8792a-c2d2-4300-b1b2-316cf79bd995","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200395 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:20:00 INFO - 1500553200396 Marionette TRACE 362 <- [1,2,null,{}]
05:20:00 INFO - 1500553200399 Marionette DEBUG Closed connection 362
05:20:00 INFO - 1500553200399 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49764
05:20:00 INFO - 1500553200400 Marionette DEBUG Closed connection 363
05:20:00 INFO - 1500553200400 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49765
05:20:00 INFO - 1500553200401 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:00 INFO - 1500553200402 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200404 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200406 Marionette TRACE 364 <- [1,1,null,{"sessionId":"2f789006-9923-4b7f-82df-07e189c4fe95","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200408 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:20:00 INFO - 1500553200409 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49327/test.html"
05:20:00 INFO - 1500553200410 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49327/test.html"
05:20:00 INFO - 1500553200411 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49327/test.html"
05:20:00 INFO - 1500553200435 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:20:00 INFO - 1500553200436 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:20:00 INFO - 1500553200439 Marionette TRACE 364 <- [1,2,null,{}]
05:20:00 INFO - 1500553200440 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:20:00 INFO - 1500553200441 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"caff64f4-3f51-40d7-874a-5320f2ecd32c","ELEMENT":"caff64f4-3f51-40d7-874a-5320f2ecd32c"}}]
05:20:00 INFO - 1500553200442 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"caff64f4-3f51-40d7-874a-5320f2ecd32c"}]
05:20:00 INFO - 1500553200681 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:00 INFO - 1500553200682 Marionette TRACE 364 <- [1,4,null,{}]
05:20:00 INFO - 1500553200683 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"caff64f4-3f51-40d7-874a-5320f2ecd32c","name":"selected"}]
05:20:00 INFO - 1500553200687 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:20:00 INFO - 1500553200689 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:20:00 INFO - TEST-END: test_click.py TestClick.test_container_element1500553200691 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:20:00 INFO - 1500553200691 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:20:00 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 331ms
05:20:00 INFO - 1500553200693 Marionette TRACE 364 <- [1,7,null,{}]
05:20:00 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:20:00 INFO - 1500553200694 Marionette DEBUG Closed connection 364
05:20:00 INFO - 1500553200697 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49766
05:20:00 INFO - 1500553200697 Marionette DEBUG Closed connection 365
05:20:00 INFO - 1500553200698 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49767
05:20:00 INFO - 1500553200698 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:00 INFO - 1500553200699 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200701 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200703 Marionette TRACE 366 <- [1,1,null,{"sessionId":"25a86517-0864-45be-b7da-6b1f4aa7c6e9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200705 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:20:00 INFO - 1500553200706 Marionette TRACE 366 <- [1,2,null,{}]
05:20:00 INFO - 1500553200706 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:00 INFO - 1500553200707 Marionette TRACE 366 <- [1,3,null,{}]
05:20:00 INFO - 1500553200708 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:00 INFO - 1500553200709 Marionette TRACE 366 <- [1,4,null,{}]
05:20:00 INFO - 1500553200711 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:20:00 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500553200713 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:20:00 INFO - 1500553200713 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:20:00 INFO - 1500553200715 Marionette TRACE 366 <- [1,6,null,{}]
05:20:00 INFO - 1500553200716 Marionette DEBUG Closed connection 366
05:20:00 INFO - 1500553200717 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49768
05:20:00 INFO - 1500553200717 Marionette DEBUG Closed connection 367
05:20:00 INFO - 1500553200718 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49769
05:20:00 INFO - 1500553200719 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:00 INFO - 1500553200719 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200721 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200725 Marionette TRACE 368 <- [1,1,null,{"sessionId":"6ed53553-f2e4-4469-aa29-e08143c06f5b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200726 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:20:00 INFO - 1500553200727 Marionette TRACE 368 <- [1,2,null,{}]
05:20:00 INFO - 1500553200729 Marionette DEBUG Closed connection 368
05:20:00 INFO - 1500553200729 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49770
05:20:00 INFO - 1500553200730 Marionette DEBUG Closed connection 369
05:20:00 INFO - 1500553200731 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49771
05:20:00 INFO - 1500553200732 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:00 INFO - 1500553200732 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:00 INFO - 1500553200741 Marionette DEBUG Register listener.js for window 15
05:20:00 INFO - 1500553200743 Marionette TRACE 370 <- [1,1,null,{"sessionId":"f338e91a-a387-4a91-9876-429d761cd355","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:00 INFO - 1500553200745 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:20:00 INFO - 1500553200747 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:20:00 INFO - 1500553200749 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:20:00 INFO - 1500553200749 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:20:00 INFO - 1500553200759 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:20:00 INFO - 1500553200762 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:20:00 INFO - 1500553200773 Marionette TRACE 370 <- [1,2,null,{}]
05:20:00 INFO - 1500553200774 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:20:00 INFO - 1500553200776 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"df3dac66-7113-40c8-9fc3-b0d875d0c97b","ELEMENT":"df3dac66-7113-40c8-9fc3-b0d875d0c97b"}}]
05:20:00 INFO - 1500553200777 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"df3dac66-7113-40c8-9fc3-b0d875d0c97b"}]
05:20:01 INFO - 1500553201014 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:01 INFO - 1500553201015 Marionette TRACE 370 <- [1,4,null,{}]
05:20:01 INFO - 1500553201016 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"df3dac66-7113-40c8-9fc3-b0d875d0c97b","name":"selected"}]
05:20:01 INFO - 1500553201020 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:20:01 INFO - 1500553201022 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:20:01 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500553201023 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:20:01 INFO - 1500553201024 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:20:01 INFO - 1500553201026 Marionette TRACE 370 <- [1,7,null,{}]
05:20:01 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 332ms
05:20:01 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:20:01 INFO - 1500553201028 Marionette DEBUG Closed connection 370
05:20:01 INFO - 1500553201030 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49772
05:20:01 INFO - 1500553201030 Marionette DEBUG Closed connection 371
05:20:01 INFO - 1500553201031 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49773
05:20:01 INFO - 1500553201032 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:01 INFO - 1500553201033 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201035 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201037 Marionette TRACE 372 <- [1,1,null,{"sessionId":"b31ef739-8154-4a77-9934-01b1ec550d3a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201039 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:20:01 INFO - 1500553201039 Marionette TRACE 372 <- [1,2,null,{}]
05:20:01 INFO - 1500553201040 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:01 INFO - 1500553201040 Marionette TRACE 372 <- [1,3,null,{}]
05:20:01 INFO - 1500553201041 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:01 INFO - 1500553201041 Marionette TRACE 372 <- [1,4,null,{}]
05:20:01 INFO - 1500553201043 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:20:01 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500553201045 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:20:01 INFO - 1500553201046 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:20:01 INFO - 1500553201047 Marionette TRACE 372 <- [1,6,null,{}]
05:20:01 INFO - 1500553201049 Marionette DEBUG Closed connection 372
05:20:01 INFO - 1500553201049 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49774
05:20:01 INFO - 1500553201050 Marionette DEBUG Closed connection 373
05:20:01 INFO - 1500553201050 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49775
05:20:01 INFO - 1500553201051 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:01 INFO - 1500553201051 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201053 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201056 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4fe71f65-7275-41bb-8233-4ee3d67e9968","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201057 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:20:01 INFO - 1500553201058 Marionette TRACE 374 <- [1,2,null,{}]
05:20:01 INFO - 1500553201060 Marionette DEBUG Closed connection 374
05:20:01 INFO - 1500553201060 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49776
05:20:01 INFO - 1500553201061 Marionette DEBUG Closed connection 375
05:20:01 INFO - 1500553201061 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49777
05:20:01 INFO - 1500553201062 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:01 INFO - 1500553201063 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201066 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201068 Marionette TRACE 376 <- [1,1,null,{"sessionId":"2a2cfb68-94ed-45fe-9fdb-14f1bbc89df7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201070 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:20:01 INFO - 1500553201071 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:20:01 INFO - 1500553201073 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:20:01 INFO - 1500553201073 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:20:01 INFO - 1500553201089 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:20:01 INFO - 1500553201091 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:20:01 INFO - 1500553201094 Marionette TRACE 376 <- [1,2,null,{}]
05:20:01 INFO - 1500553201095 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:20:01 INFO - 1500553201102 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe809eac-17e1-4d2b-9565-d9c2d1e0ddbd","ELEMENT":"fe809eac-17e1-4d2b-9565-d9c2d1e0ddbd"}}]
05:20:01 INFO - 1500553201104 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"fe809eac-17e1-4d2b-9565-d9c2d1e0ddbd"}]
05:20:01 INFO - 1500553201347 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:01 INFO - 1500553201348 Marionette TRACE 376 <- [1,4,null,{}]
05:20:01 INFO - 1500553201349 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:20:01 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500553201351 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:20:01 INFO - 1500553201352 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:20:01 INFO - 1500553201353 Marionette TRACE 376 <- [1,6,null,{}]
05:20:01 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 326ms
05:20:01 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:20:01 INFO - 1500553201355 Marionette DEBUG Closed connection 376
05:20:01 INFO - 1500553201356 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49778
05:20:01 INFO - 1500553201356 Marionette DEBUG Closed connection 377
05:20:01 INFO - 1500553201357 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49779
05:20:01 INFO - 1500553201358 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:01 INFO - 1500553201358 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201360 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201363 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6de82c58-6374-4104-90b9-beb0c82e3341","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201365 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:20:01 INFO - 1500553201365 Marionette TRACE 378 <- [1,2,null,{}]
05:20:01 INFO - 1500553201366 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:01 INFO - 1500553201366 Marionette TRACE 378 <- [1,3,null,{}]
05:20:01 INFO - 1500553201367 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:01 INFO - 1500553201368 Marionette TRACE 378 <- [1,4,null,{}]
05:20:01 INFO - 1500553201369 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:20:01 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500553201371 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:20:01 INFO - 1500553201372 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:20:01 INFO - 1500553201373 Marionette TRACE 378 <- [1,6,null,{}]
05:20:01 INFO - 1500553201374 Marionette DEBUG Closed connection 378
05:20:01 INFO - 1500553201375 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49780
05:20:01 INFO - 1500553201376 Marionette DEBUG Closed connection 379
05:20:01 INFO - 1500553201376 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49781
05:20:01 INFO - 1500553201377 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:01 INFO - 1500553201378 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201379 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201382 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3f6f224b-bc7d-4cf1-9515-41a0d3f26d7b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201384 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:20:01 INFO - 1500553201385 Marionette TRACE 380 <- [1,2,null,{}]
05:20:01 INFO - 1500553201386 Marionette DEBUG Closed connection 380
05:20:01 INFO - 1500553201387 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49782
05:20:01 INFO - 1500553201387 Marionette DEBUG Closed connection 381
05:20:01 INFO - 1500553201388 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49783
05:20:01 INFO - 1500553201389 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:01 INFO - 1500553201389 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201391 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201393 Marionette TRACE 382 <- [1,1,null,{"sessionId":"90868e33-8f18-4a5e-a727-20d5276255dc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201395 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:20:01 INFO - 1500553201396 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:20:01 INFO - 1500553201398 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:20:01 INFO - 1500553201398 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:20:01 INFO - 1500553201407 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:20:01 INFO - 1500553201410 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:20:01 INFO - 1500553201412 Marionette TRACE 382 <- [1,2,null,{}]
05:20:01 INFO - 1500553201414 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:20:01 INFO - 1500553201423 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"15ad4285-acec-4253-aeec-8c416ba66c9e","ELEMENT":"15ad4285-acec-4253-aeec-8c416ba66c9e"}}]
05:20:01 INFO - 1500553201424 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"15ad4285-acec-4253-aeec-8c416ba66c9e"}]
05:20:01 INFO - 1500553201662 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:01 INFO - 1500553201663 Marionette TRACE 382 <- [1,4,null,{}]
05:20:01 INFO - 1500553201665 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:20:01 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500553201667 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:20:01 INFO - 1500553201668 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:20:01 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
05:20:01 INFO - TEST-START | test_click.py TestClick.test_input_file
05:20:01 INFO - 1500553201669 Marionette TRACE 382 <- [1,6,null,{}]
05:20:01 INFO - 1500553201671 Marionette DEBUG Closed connection 382
05:20:01 INFO - 1500553201672 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49784
05:20:01 INFO - 1500553201672 Marionette DEBUG Closed connection 383
05:20:01 INFO - 1500553201673 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49785
05:20:01 INFO - 1500553201674 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:01 INFO - 1500553201674 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201677 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201679 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ff4afd50-8095-4803-b783-48e1036d13ec","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201681 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:20:01 INFO - 1500553201682 Marionette TRACE 384 <- [1,2,null,{}]
05:20:01 INFO - 1500553201682 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:01 INFO - 1500553201683 Marionette TRACE 384 <- [1,3,null,{}]
05:20:01 INFO - 1500553201683 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:01 INFO - 1500553201684 Marionette TRACE 384 <- [1,4,null,{}]
05:20:01 INFO - 1500553201685 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:20:01 INFO - TEST-START: test_click.py TestClick.test_input_file1500553201687 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:20:01 INFO - 1500553201688 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:20:01 INFO - 1500553201689 Marionette TRACE 384 <- [1,6,null,{}]
05:20:01 INFO - 1500553201691 Marionette DEBUG Closed connection 384
05:20:01 INFO - 1500553201691 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49786
05:20:01 INFO - 1500553201692 Marionette DEBUG Closed connection 385
05:20:01 INFO - 1500553201693 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49787
05:20:01 INFO - 1500553201693 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:01 INFO - 1500553201694 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201696 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201698 Marionette TRACE 386 <- [1,1,null,{"sessionId":"510233da-0a2e-4156-859d-5fbf4c3b0bc8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201700 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:20:01 INFO - 1500553201701 Marionette TRACE 386 <- [1,2,null,{}]
05:20:01 INFO - 1500553201703 Marionette DEBUG Closed connection 386
05:20:01 INFO - 1500553201704 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49788
05:20:01 INFO - 1500553201704 Marionette DEBUG Closed connection 387
05:20:01 INFO - 1500553201705 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49789
05:20:01 INFO - 1500553201706 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:01 INFO - 1500553201706 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201708 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201710 Marionette TRACE 388 <- [1,1,null,{"sessionId":"525e65d2-0a3a-4cd2-840e-3b74d1ee22d8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201719 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:20:01 INFO - 1500553201720 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:20:01 INFO - 1500553201722 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:20:01 INFO - 1500553201722 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:20:01 INFO - 1500553201731 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:01 INFO - 1500553201740 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:01 INFO - 1500553201743 Marionette TRACE 388 <- [1,2,null,{}]
05:20:01 INFO - 1500553201744 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:20:01 INFO - 1500553201746 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2314ac3d-abe1-415b-8727-f4c380c5e8ac","ELEMENT":"2314ac3d-abe1-415b-8727-f4c380c5e8ac"}}]
05:20:01 INFO - 1500553201746 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2314ac3d-abe1-415b-8727-f4c380c5e8ac"}]
05:20:01 INFO - 1500553201775 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
05:20:01 INFO - 1500553201777 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:20:01 INFO - TEST-END: test_click.py TestClick.test_input_file1500553201779 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:20:01 INFO - 1500553201780 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:20:01 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 112ms
05:20:01 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:20:01 INFO - 1500553201782 Marionette TRACE 388 <- [1,6,null,{}]
05:20:01 INFO - 1500553201784 Marionette DEBUG Closed connection 388
05:20:01 INFO - 1500553201785 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49790
05:20:01 INFO - 1500553201786 Marionette DEBUG Closed connection 389
05:20:01 INFO - 1500553201786 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49791
05:20:01 INFO - 1500553201787 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:01 INFO - 1500553201788 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201790 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201792 Marionette TRACE 390 <- [1,1,null,{"sessionId":"030367a9-75d1-4017-913a-8c6815325c1c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201794 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:20:01 INFO - 1500553201794 Marionette TRACE 390 <- [1,2,null,{}]
05:20:01 INFO - 1500553201795 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:01 INFO - 1500553201796 Marionette TRACE 390 <- [1,3,null,{}]
05:20:01 INFO - 1500553201796 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:01 INFO - 1500553201797 Marionette TRACE 390 <- [1,4,null,{}]
05:20:01 INFO - 1500553201798 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:20:01 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500553201800 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:20:01 INFO - 1500553201801 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:20:01 INFO - 1500553201802 Marionette TRACE 390 <- [1,6,null,{}]
05:20:01 INFO - 1500553201804 Marionette DEBUG Closed connection 390
05:20:01 INFO - 1500553201805 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49792
05:20:01 INFO - 1500553201805 Marionette DEBUG Closed connection 391
05:20:01 INFO - 1500553201806 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49793
05:20:01 INFO - 1500553201807 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:01 INFO - 1500553201807 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201809 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201812 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d1b56980-32f2-4739-a3ab-ba98c9b820fc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201813 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:20:01 INFO - 1500553201814 Marionette TRACE 392 <- [1,2,null,{}]
05:20:01 INFO - 1500553201816 Marionette DEBUG Closed connection 392
05:20:01 INFO - 1500553201817 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49794
05:20:01 INFO - 1500553201817 Marionette DEBUG Closed connection 393
05:20:01 INFO - 1500553201818 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49795
05:20:01 INFO - 1500553201819 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:01 INFO - 1500553201819 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:01 INFO - 1500553201821 Marionette DEBUG Register listener.js for window 15
05:20:01 INFO - 1500553201824 Marionette TRACE 394 <- [1,1,null,{"sessionId":"db21e44c-d806-4b74-ad32-5df8f9bd9453","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:01 INFO - 1500553201826 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:20:01 INFO - 1500553201827 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:01 INFO - 1500553201828 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:01 INFO - 1500553201829 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:01 INFO - 1500553201845 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:20:01 INFO - 1500553201847 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:20:01 INFO - 1500553201849 Marionette TRACE 394 <- [1,2,null,{}]
05:20:01 INFO - 1500553201855 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:20:01 INFO - 1500553201857 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2355fe78-70ca-416b-af2c-e3237cde717b","ELEMENT":"2355fe78-70ca-416b-af2c-e3237cde717b"}}]
05:20:01 INFO - 1500553201858 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:20:01 INFO - 1500553201860 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9400c5ec-4aa0-417a-92c5-6d523f052739","ELEMENT":"9400c5ec-4aa0-417a-92c5-6d523f052739"}}]
05:20:01 INFO - 1500553201860 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2355fe78-70ca-416b-af2c-e3237cde717b"}]
05:20:02 INFO - 1500553202102 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:02 INFO - 1500553202102 Marionette TRACE 394 <- [1,5,null,{}]
05:20:02 INFO - 1500553202103 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9400c5ec-4aa0-417a-92c5-6d523f052739"}]
05:20:02 INFO - 1500553202117 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
05:20:02 INFO - 1500553202119 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
05:20:02 INFO - 1500553202121 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:20:02 INFO - 1500553202122 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:20:02 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500553202124 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:20:02 INFO - 1500553202125 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:20:02 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 345ms
05:20:02 INFO - 1500553202126 Marionette TRACE 394 <- [1,9,null,{}]
05:20:02 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:20:02 INFO - 1500553202128 Marionette DEBUG Closed connection 394
05:20:02 INFO - 1500553202129 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49796
05:20:02 INFO - 1500553202130 Marionette DEBUG Closed connection 395
05:20:02 INFO - 1500553202130 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49797
05:20:02 INFO - 1500553202131 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:02 INFO - 1500553202132 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:02 INFO - 1500553202134 Marionette DEBUG Register listener.js for window 15
05:20:02 INFO - 1500553202136 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9c8096fc-e77c-4fa9-bee2-f586515d2a02","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:02 INFO - 1500553202138 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:20:02 INFO - 1500553202138 Marionette TRACE 396 <- [1,2,null,{}]
05:20:02 INFO - 1500553202139 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:02 INFO - 1500553202139 Marionette TRACE 396 <- [1,3,null,{}]
05:20:02 INFO - 1500553202140 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:02 INFO - 1500553202140 Marionette TRACE 396 <- [1,4,null,{}]
05:20:02 INFO - 1500553202143 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:20:02 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500553202145 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:20:02 INFO - 1500553202145 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:20:02 INFO - 1500553202146 Marionette TRACE 396 <- [1,6,null,{}]
05:20:02 INFO - 1500553202148 Marionette DEBUG Closed connection 396
05:20:02 INFO - 1500553202148 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49798
05:20:02 INFO - 1500553202149 Marionette DEBUG Closed connection 397
05:20:02 INFO - 1500553202150 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49799
05:20:02 INFO - 1500553202150 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:02 INFO - 1500553202151 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:02 INFO - 1500553202153 Marionette DEBUG Register listener.js for window 15
05:20:02 INFO - 1500553202156 Marionette TRACE 398 <- [1,1,null,{"sessionId":"71bf2df2-8113-49b1-bb87-3e5d19b6e6a8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:02 INFO - 1500553202157 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:20:02 INFO - 1500553202158 Marionette TRACE 398 <- [1,2,null,{}]
05:20:02 INFO - 1500553202160 Marionette DEBUG Closed connection 398
05:20:02 INFO - 1500553202161 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49800
05:20:02 INFO - 1500553202161 Marionette DEBUG Closed connection 399
05:20:02 INFO - 1500553202162 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49801
05:20:02 INFO - 1500553202163 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:02 INFO - 1500553202163 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}
05:20:02 INFO - 1500553202165 Marionette DEBUG Register listener.js for window 15
05:20:02 INFO - 1500553202168 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e9e68f4b-390a-47b0-959a-ef5e3ede3f41","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3552,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-149.001\\AppData\\Local\\Temp\\tmpb2ywce.mozrunner","moz:accessibilityChecks":false}}]
05:20:02 INFO - 1500553202170 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:20:02 INFO - 1500553202171 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:20:02 INFO - 1500553202172 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:20:02 INFO - 1500553202173 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:20:02 INFO - 1500553202191 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:20:02 INFO - 1500553202192 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:20:02 INFO - 1500553202194 Marionette TRACE 400 <- [1,2,null,{}]
05:20:02 INFO - 1500553202195 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:20:02 INFO - 1500553202197 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e2302281-cc8c-4547-b4ae-6f2eff2cddc4","ELEMENT":"e2302281-cc8c-4547-b4ae-6f2eff2cddc4"}}]
05:20:02 INFO - 1500553202198 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e2302281-cc8c-4547-b4ae-6f2eff2cddc4"}]
05:20:02 INFO - 1500553202225 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:20:02 INFO - 1500553202226 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e2302281-cc8c-4547-b4ae-6f2eff2cddc4"}]
05:20:02 INFO - 1500553202240 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element