could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
18:04:08 INFO - 1499389448540 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}]
18:04:08 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499389448543 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:04:08 INFO - 1499389448544 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:04:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 138ms
18:04:08 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:04:08 INFO - 1499389448546 Marionette TRACE 352 <- [1,6,null,{}]
18:04:08 INFO - 1499389448547 Marionette DEBUG Closed connection 352
18:04:08 INFO - 1499389448548 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49793
18:04:08 INFO - 1499389448549 Marionette DEBUG Closed connection 353
18:04:08 INFO - 1499389448550 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49794
18:04:08 INFO - 1499389448551 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:08 INFO - 1499389448552 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:08 INFO - 1499389448555 Marionette DEBUG Register listener.js for window 4294967297
18:04:08 INFO - 1499389448559 Marionette TRACE 354 <- [1,1,null,{"sessionId":"667c1042-da80-40b8-bc3a-a50a961459f9","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:08 INFO - 1499389448560 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:04:08 INFO - 1499389448560 Marionette TRACE 354 <- [1,2,null,{}]
18:04:08 INFO - 1499389448561 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:04:08 INFO - 1499389448562 Marionette TRACE 354 <- [1,3,null,{}]
18:04:08 INFO - 1499389448563 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:04:08 INFO - 1499389448564 Marionette TRACE 354 <- [1,4,null,{}]
18:04:08 INFO - 1499389448565 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}]
18:04:08 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499389448568 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:04:08 INFO - 1499389448569 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:04:08 INFO - 1499389448571 Marionette TRACE 354 <- [1,6,null,{}]
18:04:08 INFO - 1499389448573 Marionette DEBUG Closed connection 354
18:04:08 INFO - 1499389448574 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49795
18:04:08 INFO - 1499389448575 Marionette DEBUG Closed connection 355
18:04:08 INFO - 1499389448575 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49796
18:04:08 INFO - 1499389448576 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:08 INFO - 1499389448577 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:08 INFO - 1499389448580 Marionette DEBUG Register listener.js for window 4294967297
18:04:08 INFO - 1499389448583 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4ef30c79-54cb-46bc-a3b7-754027aee7b0","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:08 INFO - 1499389448584 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:04:08 INFO - 1499389448586 Marionette TRACE 356 <- [1,2,null,{}]
18:04:08 INFO - 1499389448587 Marionette DEBUG Closed connection 356
18:04:08 INFO - 1499389448588 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49797
18:04:08 INFO - 1499389448589 Marionette DEBUG Closed connection 357
18:04:08 INFO - 1499389448590 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49798
18:04:08 INFO - 1499389448592 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:04:08 INFO - 1499389448592 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:08 INFO - 1499389448595 Marionette DEBUG Register listener.js for window 4294967297
18:04:08 INFO - 1499389448598 Marionette TRACE 358 <- [1,1,null,{"sessionId":"8d6910e3-ff87-4675-9968-36f7a171fca7","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:08 INFO - 1499389448600 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49348/clicks.html"}]
18:04:08 INFO - 1499389448602 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49348/hidden.html"
18:04:08 INFO - 1499389448615 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49348/hidden.html"
18:04:08 INFO - 1499389448615 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49348/hidden.html"
18:04:08 INFO - 1499389448623 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49348/clicks.html"
18:04:08 INFO - 1499389448631 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49348/clicks.html"
18:04:08 INFO - 1499389448638 Marionette TRACE 358 <- [1,2,null,{}]
18:04:08 INFO - 1499389448650 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:04:08 INFO - 1499389448656 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"977562f1-233e-4373-9245-d56de5f23bff","ELEMENT":"977562f1-233e-4373-9245-d56de5f23bff"}}]
18:04:08 INFO - 1499389448658 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"977562f1-233e-4373-9245-d56de5f23bff"}]
18:04:08 INFO - 1499389448709 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49348/clicks.html"
18:04:08 INFO - 1499389448717 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49348/clicks.html"
18:04:08 INFO - 1499389448718 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49348/clicks.html"
18:04:08 INFO - 1499389448728 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49348/test.html"
18:04:08 INFO - 1499389448730 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49348/test.html"
18:04:08 INFO - 1499389448735 Marionette TRACE 358 <- [1,4,null,{}]
18:04:08 INFO - 1499389448740 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:04:08 INFO - 1499389448747 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"778c7448-76bd-4686-b735-24533815aa64","ELEMENT":"778c7448-76bd-4686-b735-24533815aa64"}}]
18:04:08 INFO - 1499389448749 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:04:08 INFO - 1499389448749 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:04:08 INFO - 1499389448751 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}]
18:04:08 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499389448754 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:04:08 INFO - 1499389448756 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:04:08 INFO - 1499389448757 Marionette TRACE 358 <- [1,8,null,{}]
18:04:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 211ms
18:04:08 INFO - TEST-START | test_click.py TestClick.test_container_element
18:04:08 INFO - 1499389448759 Marionette DEBUG Closed connection 358
18:04:08 INFO - 1499389448760 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49801
18:04:08 INFO - 1499389448761 Marionette DEBUG Closed connection 359
18:04:08 INFO - 1499389448762 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49802
18:04:08 INFO - 1499389448763 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:08 INFO - 1499389448764 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:08 INFO - 1499389448767 Marionette DEBUG Register listener.js for window 4294967297
18:04:08 INFO - 1499389448770 Marionette TRACE 360 <- [1,1,null,{"sessionId":"06e78738-822d-4eaa-8cf6-20460ca67bda","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:08 INFO - 1499389448772 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:04:08 INFO - 1499389448772 Marionette TRACE 360 <- [1,2,null,{}]
18:04:08 INFO - 1499389448773 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:04:08 INFO - 1499389448774 Marionette TRACE 360 <- [1,3,null,{}]
18:04:08 INFO - 1499389448775 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:04:08 INFO - 1499389448775 Marionette TRACE 360 <- [1,4,null,{}]
18:04:08 INFO - 1499389448777 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}]
18:04:08 INFO - TEST-START: test_click.py TestClick.test_container_element1499389448781 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:04:08 INFO - 1499389448782 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:04:08 INFO - 1499389448783 Marionette TRACE 360 <- [1,6,null,{}]
18:04:08 INFO - 1499389448785 Marionette DEBUG Closed connection 360
18:04:08 INFO - 1499389448786 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49803
18:04:08 INFO - 1499389448787 Marionette DEBUG Closed connection 361
18:04:08 INFO - 1499389448787 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49804
18:04:08 INFO - 1499389448789 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:08 INFO - 1499389448789 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:08 INFO - 1499389448792 Marionette DEBUG Register listener.js for window 4294967297
18:04:08 INFO - 1499389448796 Marionette TRACE 362 <- [1,1,null,{"sessionId":"2d2b046e-effc-45c2-a1be-75384d0dac5c","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:08 INFO - 1499389448797 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:04:08 INFO - 1499389448798 Marionette TRACE 362 <- [1,2,null,{}]
18:04:08 INFO - 1499389448800 Marionette DEBUG Closed connection 362
18:04:08 INFO - 1499389448801 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49805
18:04:08 INFO - 1499389448802 Marionette DEBUG Closed connection 363
18:04:08 INFO - 1499389448802 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49806
18:04:08 INFO - 1499389448803 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:04:08 INFO - 1499389448804 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:08 INFO - 1499389448807 Marionette DEBUG Register listener.js for window 4294967297
18:04:08 INFO - 1499389448810 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f733718e-2603-41e5-b216-76c107fb71ec","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:08 INFO - 1499389448811 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"}]
18:04:08 INFO - 1499389448813 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49348/test.html"
18:04:08 INFO - 1499389448816 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49348/test.html"
18:04:08 INFO - 1499389448816 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49348/test.html"
18:04:08 INFO - 1499389448823 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"
18:04:08 INFO - 1499389448829 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"
18:04:08 INFO - 1499389448839 Marionette TRACE 364 <- [1,2,null,{}]
18:04:08 INFO - 1499389448848 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:04:08 INFO - 1499389448855 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7194e113-55ba-4064-ad52-5c82e131291f","ELEMENT":"7194e113-55ba-4064-ad52-5c82e131291f"}}]
18:04:08 INFO - 1499389448856 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7194e113-55ba-4064-ad52-5c82e131291f"}]
18:04:09 INFO - 1499389449103 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:04:09 INFO - 1499389449105 Marionette TRACE 364 <- [1,4,null,{}]
18:04:09 INFO - 1499389449106 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7194e113-55ba-4064-ad52-5c82e131291f","name":"selected"}]
18:04:09 INFO - 1499389449111 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:04:09 INFO - 1499389449113 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}]
18:04:09 INFO - TEST-END: test_click.py TestClick.test_container_element1499389449116 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:04:09 INFO - 1499389449117 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:04:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 361ms
18:04:09 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:04:09 INFO - 1499389449119 Marionette TRACE 364 <- [1,7,null,{}]
18:04:09 INFO - 1499389449121 Marionette DEBUG Closed connection 364
18:04:09 INFO - 1499389449122 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49807
18:04:09 INFO - 1499389449122 Marionette DEBUG Closed connection 365
18:04:09 INFO - 1499389449123 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49808
18:04:09 INFO - 1499389449124 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:09 INFO - 1499389449125 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449128 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449131 Marionette TRACE 366 <- [1,1,null,{"sessionId":"42f69d3c-0bbe-45bd-b66e-3c148c98e4f7","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449132 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:04:09 INFO - 1499389449133 Marionette TRACE 366 <- [1,2,null,{}]
18:04:09 INFO - 1499389449134 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:04:09 INFO - 1499389449135 Marionette TRACE 366 <- [1,3,null,{}]
18:04:09 INFO - 1499389449136 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:04:09 INFO - 1499389449136 Marionette TRACE 366 <- [1,4,null,{}]
18:04:09 INFO - 1499389449138 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}]
18:04:09 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499389449141 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:04:09 INFO - 1499389449142 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:04:09 INFO - 1499389449143 Marionette TRACE 366 <- [1,6,null,{}]
18:04:09 INFO - 1499389449145 Marionette DEBUG Closed connection 366
18:04:09 INFO - 1499389449146 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49809
18:04:09 INFO - 1499389449147 Marionette DEBUG Closed connection 367
18:04:09 INFO - 1499389449147 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49810
18:04:09 INFO - 1499389449148 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:09 INFO - 1499389449149 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449152 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449155 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e8c33945-a812-4943-b965-27be730a37d3","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449156 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:04:09 INFO - 1499389449158 Marionette TRACE 368 <- [1,2,null,{}]
18:04:09 INFO - 1499389449160 Marionette DEBUG Closed connection 368
18:04:09 INFO - 1499389449161 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49811
18:04:09 INFO - 1499389449161 Marionette DEBUG Closed connection 369
18:04:09 INFO - 1499389449162 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49812
18:04:09 INFO - 1499389449163 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:04:09 INFO - 1499389449164 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449167 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449170 Marionette TRACE 370 <- [1,1,null,{"sessionId":"902afd3a-901c-45c0-bc37-a39f1ebd672f","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449172 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"}]
18:04:09 INFO - 1499389449174 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"
18:04:09 INFO - 1499389449178 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"
18:04:09 INFO - 1499389449178 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"
18:04:09 INFO - 1499389449187 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"
18:04:09 INFO - 1499389449193 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"
18:04:09 INFO - 1499389449199 Marionette TRACE 370 <- [1,2,null,{}]
18:04:09 INFO - 1499389449203 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:04:09 INFO - 1499389449207 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"55cd6bc3-b822-43fc-931f-ea8c530c1c26","ELEMENT":"55cd6bc3-b822-43fc-931f-ea8c530c1c26"}}]
18:04:09 INFO - 1499389449213 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"55cd6bc3-b822-43fc-931f-ea8c530c1c26"}]
18:04:09 INFO - 1499389449459 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:04:09 INFO - 1499389449460 Marionette TRACE 370 <- [1,4,null,{}]
18:04:09 INFO - 1499389449461 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"55cd6bc3-b822-43fc-931f-ea8c530c1c26","name":"selected"}]
18:04:09 INFO - 1499389449466 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:04:09 INFO - 1499389449468 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}]
18:04:09 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499389449471 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:04:09 INFO - 1499389449472 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:04:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 354ms
18:04:09 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:04:09 INFO - 1499389449474 Marionette TRACE 370 <- [1,7,null,{}]
18:04:09 INFO - 1499389449475 Marionette DEBUG Closed connection 370
18:04:09 INFO - 1499389449476 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49813
18:04:09 INFO - 1499389449477 Marionette DEBUG Closed connection 371
18:04:09 INFO - 1499389449478 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49814
18:04:09 INFO - 1499389449479 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:09 INFO - 1499389449480 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449483 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449486 Marionette TRACE 372 <- [1,1,null,{"sessionId":"13f55a3c-a606-48c3-b255-d46271222892","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449487 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:04:09 INFO - 1499389449488 Marionette TRACE 372 <- [1,2,null,{}]
18:04:09 INFO - 1499389449489 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:04:09 INFO - 1499389449489 Marionette TRACE 372 <- [1,3,null,{}]
18:04:09 INFO - 1499389449490 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:04:09 INFO - 1499389449491 Marionette TRACE 372 <- [1,4,null,{}]
18:04:09 INFO - 1499389449493 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}]
18:04:09 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499389449496 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:04:09 INFO - 1499389449497 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:04:09 INFO - 1499389449498 Marionette TRACE 372 <- [1,6,null,{}]
18:04:09 INFO - 1499389449500 Marionette DEBUG Closed connection 372
18:04:09 INFO - 1499389449501 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49815
18:04:09 INFO - 1499389449501 Marionette DEBUG Closed connection 373
18:04:09 INFO - 1499389449502 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49816
18:04:09 INFO - 1499389449503 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:09 INFO - 1499389449504 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449507 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449510 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2ecdee0a-3dbd-451b-a05c-4cec46725bef","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449511 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:04:09 INFO - 1499389449512 Marionette TRACE 374 <- [1,2,null,{}]
18:04:09 INFO - 1499389449514 Marionette DEBUG Closed connection 374
18:04:09 INFO - 1499389449515 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49817
18:04:09 INFO - 1499389449516 Marionette DEBUG Closed connection 375
18:04:09 INFO - 1499389449516 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49818
18:04:09 INFO - 1499389449517 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:04:09 INFO - 1499389449518 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449521 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449524 Marionette TRACE 376 <- [1,1,null,{"sessionId":"76f586ab-e697-42ca-a072-599973659141","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449525 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"}]
18:04:09 INFO - 1499389449527 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"
18:04:09 INFO - 1499389449529 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"
18:04:09 INFO - 1499389449530 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"
18:04:09 INFO - 1499389449537 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"
18:04:09 INFO - 1499389449540 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"
18:04:09 INFO - 1499389449550 Marionette TRACE 376 <- [1,2,null,{}]
18:04:09 INFO - 1499389449555 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:04:09 INFO - 1499389449558 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"515b999d-1bd4-415c-8dc6-abf03103bc02","ELEMENT":"515b999d-1bd4-415c-8dc6-abf03103bc02"}}]
18:04:09 INFO - 1499389449564 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"515b999d-1bd4-415c-8dc6-abf03103bc02"}]
18:04:09 INFO - 1499389449812 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:04:09 INFO - 1499389449813 Marionette TRACE 376 <- [1,4,null,{}]
18:04:09 INFO - 1499389449815 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}]
18:04:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499389449818 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:04:09 INFO - 1499389449819 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:04:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
18:04:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:04:09 INFO - 1499389449821 Marionette TRACE 376 <- [1,6,null,{}]
18:04:09 INFO - 1499389449822 Marionette DEBUG Closed connection 376
18:04:09 INFO - 1499389449823 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49819
18:04:09 INFO - 1499389449824 Marionette DEBUG Closed connection 377
18:04:09 INFO - 1499389449825 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49820
18:04:09 INFO - 1499389449826 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:09 INFO - 1499389449827 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449830 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449833 Marionette TRACE 378 <- [1,1,null,{"sessionId":"87b55037-1a77-43b3-8c7a-7d5a89f6e97a","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449835 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:04:09 INFO - 1499389449835 Marionette TRACE 378 <- [1,2,null,{}]
18:04:09 INFO - 1499389449836 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:04:09 INFO - 1499389449837 Marionette TRACE 378 <- [1,3,null,{}]
18:04:09 INFO - 1499389449837 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:04:09 INFO - 1499389449838 Marionette TRACE 378 <- [1,4,null,{}]
18:04:09 INFO - 1499389449840 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}]
18:04:09 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499389449843 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:04:09 INFO - 1499389449844 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:04:09 INFO - 1499389449845 Marionette TRACE 378 <- [1,6,null,{}]
18:04:09 INFO - 1499389449847 Marionette DEBUG Closed connection 378
18:04:09 INFO - 1499389449848 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49821
18:04:09 INFO - 1499389449849 Marionette DEBUG Closed connection 379
18:04:09 INFO - 1499389449850 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49822
18:04:09 INFO - 1499389449851 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:09 INFO - 1499389449851 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449854 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449857 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a2cd16c0-7611-4c5d-85f6-cf92c3a13572","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449858 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:04:09 INFO - 1499389449860 Marionette TRACE 380 <- [1,2,null,{}]
18:04:09 INFO - 1499389449862 Marionette DEBUG Closed connection 380
18:04:09 INFO - 1499389449862 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49823
18:04:09 INFO - 1499389449863 Marionette DEBUG Closed connection 381
18:04:09 INFO - 1499389449864 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49824
18:04:09 INFO - 1499389449865 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:04:09 INFO - 1499389449866 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:09 INFO - 1499389449868 Marionette DEBUG Register listener.js for window 4294967297
18:04:09 INFO - 1499389449872 Marionette TRACE 382 <- [1,1,null,{"sessionId":"5b0413cb-ac9b-4bc7-abe3-94e8c8c737bf","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:09 INFO - 1499389449873 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"}]
18:04:09 INFO - 1499389449875 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"
18:04:09 INFO - 1499389449877 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"
18:04:09 INFO - 1499389449877 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"
18:04:09 INFO - 1499389449884 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"
18:04:09 INFO - 1499389449888 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"
18:04:09 INFO - 1499389449891 Marionette TRACE 382 <- [1,2,null,{}]
18:04:09 INFO - 1499389449900 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:04:09 INFO - 1499389449906 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8da569ed-322c-4921-9d60-3f1f6a3af3df","ELEMENT":"8da569ed-322c-4921-9d60-3f1f6a3af3df"}}]
18:04:09 INFO - 1499389449907 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"8da569ed-322c-4921-9d60-3f1f6a3af3df"}]
18:04:10 INFO - 1499389450149 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:04:10 INFO - 1499389450150 Marionette TRACE 382 <- [1,4,null,{}]
18:04:10 INFO - 1499389450152 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}]
18:04:10 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499389450155 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:04:10 INFO - 1499389450156 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:04:10 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 337ms
18:04:10 INFO - TEST-START | test_click.py TestClick.test_input_file
18:04:10 INFO - 1499389450158 Marionette TRACE 382 <- [1,6,null,{}]
18:04:10 INFO - 1499389450160 Marionette DEBUG Closed connection 382
18:04:10 INFO - 1499389450160 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49825
18:04:10 INFO - 1499389450161 Marionette DEBUG Closed connection 383
18:04:10 INFO - 1499389450162 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49826
18:04:10 INFO - 1499389450163 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:10 INFO - 1499389450164 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450167 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450170 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f732bda4-ca27-4299-a600-cbc3c47e5907","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450171 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:04:10 INFO - 1499389450172 Marionette TRACE 384 <- [1,2,null,{}]
18:04:10 INFO - 1499389450173 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:04:10 INFO - 1499389450173 Marionette TRACE 384 <- [1,3,null,{}]
18:04:10 INFO - 1499389450174 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:04:10 INFO - 1499389450175 Marionette TRACE 384 <- [1,4,null,{}]
18:04:10 INFO - 1499389450177 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}]
18:04:10 INFO - TEST-START: test_click.py TestClick.test_input_file1499389450180 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:04:10 INFO - 1499389450181 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:04:10 INFO - 1499389450182 Marionette TRACE 384 <- [1,6,null,{}]
18:04:10 INFO - 1499389450184 Marionette DEBUG Closed connection 384
18:04:10 INFO - 1499389450185 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49827
18:04:10 INFO - 1499389450186 Marionette DEBUG Closed connection 385
18:04:10 INFO - 1499389450186 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49828
18:04:10 INFO - 1499389450187 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:10 INFO - 1499389450188 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450191 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450194 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d9740488-9ff9-49be-95a3-3901877210de","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450196 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:04:10 INFO - 1499389450197 Marionette TRACE 386 <- [1,2,null,{}]
18:04:10 INFO - 1499389450199 Marionette DEBUG Closed connection 386
18:04:10 INFO - 1499389450199 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49829
18:04:10 INFO - 1499389450200 Marionette DEBUG Closed connection 387
18:04:10 INFO - 1499389450201 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49830
18:04:10 INFO - 1499389450202 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:04:10 INFO - 1499389450203 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450206 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450209 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2fbdd893-88d2-4c8c-b4a9-466fb144f1ba","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450210 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:04:10 INFO - 1499389450212 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"
18:04:10 INFO - 1499389450214 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"
18:04:10 INFO - 1499389450215 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"
18:04:10 INFO - 1499389450222 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:04:10 INFO - 1499389450227 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:04:10 INFO - 1499389450237 Marionette TRACE 388 <- [1,2,null,{}]
18:04:10 INFO - 1499389450246 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:04:10 INFO - 1499389450249 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0eee0f37-2249-4cbf-bc5e-469fb0db9ebb","ELEMENT":"0eee0f37-2249-4cbf-bc5e-469fb0db9ebb"}}]
18:04:10 INFO - 1499389450250 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"0eee0f37-2249-4cbf-bc5e-469fb0db9ebb"}]
18:04:10 INFO - 1499389450278 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
18:04:10 INFO - 1499389450281 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}]
18:04:10 INFO - TEST-END: test_click.py TestClick.test_input_file1499389450284 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:04:10 INFO - 1499389450285 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:04:10 INFO - 1499389450286 Marionette TRACE 388 <- [1,6,null,{}]
18:04:10 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 128ms
18:04:10 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:04:10 INFO - 1499389450288 Marionette DEBUG Closed connection 388
18:04:10 INFO - 1499389450289 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49831
18:04:10 INFO - 1499389450290 Marionette DEBUG Closed connection 389
18:04:10 INFO - 1499389450291 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49832
18:04:10 INFO - 1499389450292 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:10 INFO - 1499389450302 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450305 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450309 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1204a935-c002-4c6c-a96a-218214f3d7ee","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450310 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:04:10 INFO - 1499389450311 Marionette TRACE 390 <- [1,2,null,{}]
18:04:10 INFO - 1499389450312 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:04:10 INFO - 1499389450312 Marionette TRACE 390 <- [1,3,null,{}]
18:04:10 INFO - 1499389450313 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:04:10 INFO - 1499389450314 Marionette TRACE 390 <- [1,4,null,{}]
18:04:10 INFO - 1499389450316 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}]
18:04:10 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499389450319 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:04:10 INFO - 1499389450320 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:04:10 INFO - 1499389450322 Marionette TRACE 390 <- [1,6,null,{}]
18:04:10 INFO - 1499389450323 Marionette DEBUG Closed connection 390
18:04:10 INFO - 1499389450324 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49833
18:04:10 INFO - 1499389450325 Marionette DEBUG Closed connection 391
18:04:10 INFO - 1499389450326 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49834
18:04:10 INFO - 1499389450327 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:10 INFO - 1499389450328 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450331 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450334 Marionette TRACE 392 <- [1,1,null,{"sessionId":"cda4fb81-d654-4d13-bf77-9847c3ee4a7f","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450335 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:04:10 INFO - 1499389450337 Marionette TRACE 392 <- [1,2,null,{}]
18:04:10 INFO - 1499389450338 Marionette DEBUG Closed connection 392
18:04:10 INFO - 1499389450339 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49835
18:04:10 INFO - 1499389450340 Marionette DEBUG Closed connection 393
18:04:10 INFO - 1499389450341 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49836
18:04:10 INFO - 1499389450342 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:04:10 INFO - 1499389450343 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450346 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450349 Marionette TRACE 394 <- [1,1,null,{"sessionId":"36dac3c4-b741-4f8a-946c-6c362287c023","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450350 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"}]
18:04:10 INFO - 1499389450352 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:04:10 INFO - 1499389450355 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:04:10 INFO - 1499389450355 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:04:10 INFO - 1499389450364 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"
18:04:10 INFO - 1499389450366 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"
18:04:10 INFO - 1499389450371 Marionette TRACE 394 <- [1,2,null,{}]
18:04:10 INFO - 1499389450382 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:04:10 INFO - 1499389450385 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ec8ee43d-12e8-4eb8-b065-659391b6f855","ELEMENT":"ec8ee43d-12e8-4eb8-b065-659391b6f855"}}]
18:04:10 INFO - 1499389450386 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:04:10 INFO - 1499389450389 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"247a62a9-cd91-4842-8d3c-aa6167949796","ELEMENT":"247a62a9-cd91-4842-8d3c-aa6167949796"}}]
18:04:10 INFO - 1499389450390 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"ec8ee43d-12e8-4eb8-b065-659391b6f855"}]
18:04:10 INFO - 1499389450637 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:04:10 INFO - 1499389450638 Marionette TRACE 394 <- [1,5,null,{}]
18:04:10 INFO - 1499389450640 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"247a62a9-cd91-4842-8d3c-aa6167949796"}]
18:04:10 INFO - 1499389450658 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
18:04:10 INFO - 1499389450660 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:04:10 INFO - 1499389450663 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:04:10 INFO - 1499389450665 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}]
18:04:10 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499389450668 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:04:10 INFO - 1499389450669 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:04:10 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 384ms
18:04:10 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:04:10 INFO - 1499389450670 Marionette TRACE 394 <- [1,9,null,{}]
18:04:10 INFO - 1499389450672 Marionette DEBUG Closed connection 394
18:04:10 INFO - 1499389450673 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49837
18:04:10 INFO - 1499389450674 Marionette DEBUG Closed connection 395
18:04:10 INFO - 1499389450675 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49838
18:04:10 INFO - 1499389450676 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:10 INFO - 1499389450677 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450680 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450683 Marionette TRACE 396 <- [1,1,null,{"sessionId":"d3ce6a66-69c8-4a77-a00b-a5da8ceb49da","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450684 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:04:10 INFO - 1499389450685 Marionette TRACE 396 <- [1,2,null,{}]
18:04:10 INFO - 1499389450686 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:04:10 INFO - 1499389450686 Marionette TRACE 396 <- [1,3,null,{}]
18:04:10 INFO - 1499389450687 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:04:10 INFO - 1499389450688 Marionette TRACE 396 <- [1,4,null,{}]
18:04:10 INFO - 1499389450689 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}]
18:04:10 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499389450692 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:04:10 INFO - 1499389450693 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:04:10 INFO - 1499389450695 Marionette TRACE 396 <- [1,6,null,{}]
18:04:10 INFO - 1499389450696 Marionette DEBUG Closed connection 396
18:04:10 INFO - 1499389450697 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49839
18:04:10 INFO - 1499389450698 Marionette DEBUG Closed connection 397
18:04:10 INFO - 1499389450699 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49840
18:04:10 INFO - 1499389450700 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:04:10 INFO - 1499389450701 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450703 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450707 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d5f36b09-1028-4e6e-a1ff-dead0184f2d5","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450708 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:04:10 INFO - 1499389450709 Marionette TRACE 398 <- [1,2,null,{}]
18:04:10 INFO - 1499389450712 Marionette DEBUG Closed connection 398
18:04:10 INFO - 1499389450713 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49841
18:04:10 INFO - 1499389450714 Marionette DEBUG Closed connection 399
18:04:10 INFO - 1499389450715 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49842
18:04:10 INFO - 1499389450716 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:04:10 INFO - 1499389450717 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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}
18:04:10 INFO - 1499389450719 Marionette DEBUG Register listener.js for window 4294967297
18:04:10 INFO - 1499389450722 Marionette TRACE 400 <- [1,1,null,{"sessionId":"be56a986-1054-416f-bc4b-d69dbf8b8c09","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":5808,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp3kgx_m.mozrunner","moz:accessibilityChecks":false}}]
18:04:10 INFO - 1499389450724 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"}]
18:04:10 INFO - 1499389450725 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"
18:04:10 INFO - 1499389450728 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"
18:04:10 INFO - 1499389450728 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"
18:04:10 INFO - 1499389450735 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"
18:04:10 INFO - 1499389450738 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"
18:04:10 INFO - 1499389450748 Marionette TRACE 400 <- [1,2,null,{}]
18:04:10 INFO - 1499389450752 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:04:10 INFO - 1499389450755 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7b2fd220-d346-4c68-be19-7e436dad559f","ELEMENT":"7b2fd220-d346-4c68-be19-7e436dad559f"}}]
18:04:10 INFO - 1499389450756 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"7b2fd220-d346-4c68-be19-7e436dad559f"}]
18:04:10 INFO - 1499389450779 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:04:10 INFO - 1499389450781 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"7b2fd220-d346-4c68-be19-7e436dad559f"}]
18:04:10 INFO - 1499389450799 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element