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]
21:39:42 INFO - 1500093582625 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}]
21:39:42 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500093582627 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:39:42 INFO - 1500093582628 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:39:42 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 134ms
21:39:42 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:39:42 INFO - 1500093582630 Marionette TRACE 352 <- [1,6,null,{}]
21:39:42 INFO - 1500093582632 Marionette DEBUG Closed connection 352
21:39:42 INFO - 1500093582633 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49812
21:39:42 INFO - 1500093582633 Marionette DEBUG Closed connection 353
21:39:42 INFO - 1500093582634 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49813
21:39:42 INFO - 1500093582635 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:42 INFO - 1500093582636 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:42 INFO - 1500093582638 Marionette DEBUG Register listener.js for window 15
21:39:42 INFO - 1500093582648 Marionette TRACE 354 <- [1,1,null,{"sessionId":"03053531-3025-4f29-94c8-9b3a86e0d596","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:42 INFO - 1500093582650 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:39:42 INFO - 1500093582651 Marionette TRACE 354 <- [1,2,null,{}]
21:39:42 INFO - 1500093582651 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:42 INFO - 1500093582652 Marionette TRACE 354 <- [1,3,null,{}]
21:39:42 INFO - 1500093582653 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:42 INFO - 1500093582653 Marionette TRACE 354 <- [1,4,null,{}]
21:39:42 INFO - 1500093582655 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}]
21:39:42 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500093582658 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:39:42 INFO - 1500093582659 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:39:42 INFO - 1500093582660 Marionette TRACE 354 <- [1,6,null,{}]
21:39:42 INFO - 1500093582662 Marionette DEBUG Closed connection 354
21:39:42 INFO - 1500093582662 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49814
21:39:42 INFO - 1500093582663 Marionette DEBUG Closed connection 355
21:39:42 INFO - 1500093582664 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49815
21:39:42 INFO - 1500093582665 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:42 INFO - 1500093582665 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:42 INFO - 1500093582668 Marionette DEBUG Register listener.js for window 15
21:39:42 INFO - 1500093582670 Marionette TRACE 356 <- [1,1,null,{"sessionId":"6ce9e27a-4e90-46b0-9317-86e345f55482","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:42 INFO - 1500093582672 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:39:42 INFO - 1500093582674 Marionette TRACE 356 <- [1,2,null,{}]
21:39:42 INFO - 1500093582675 Marionette DEBUG Closed connection 356
21:39:42 INFO - 1500093582676 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49816
21:39:42 INFO - 1500093582677 Marionette DEBUG Closed connection 357
21:39:42 INFO - 1500093582677 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49817
21:39:42 INFO - 1500093582678 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:42 INFO - 1500093582679 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:42 INFO - 1500093582681 Marionette DEBUG Register listener.js for window 15
21:39:42 INFO - 1500093582684 Marionette TRACE 358 <- [1,1,null,{"sessionId":"336d9e35-33bb-4301-bf16-b4af296531ea","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:42 INFO - 1500093582686 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49378/clicks.html"}]
21:39:42 INFO - 1500093582688 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49378/hidden.html"
21:39:42 INFO - 1500093582697 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49378/hidden.html"
21:39:42 INFO - 1500093582697 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49378/hidden.html"
21:39:42 INFO - 1500093582716 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49378/clicks.html"
21:39:42 INFO - 1500093582718 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49378/clicks.html"
21:39:42 INFO - 1500093582721 Marionette TRACE 358 <- [1,2,null,{}]
21:39:42 INFO - 1500093582723 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:39:42 INFO - 1500093582734 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4201795c-5ace-414d-8242-fa0fd7b7de75","ELEMENT":"4201795c-5ace-414d-8242-fa0fd7b7de75"}}]
21:39:42 INFO - 1500093582736 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"4201795c-5ace-414d-8242-fa0fd7b7de75"}]
21:39:42 INFO - 1500093582776 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49378/clicks.html"
21:39:42 INFO - 1500093582783 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49378/clicks.html"
21:39:42 INFO - 1500093582784 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49378/clicks.html"
21:39:42 INFO - 1500093582799 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49378/test.html"
21:39:42 INFO - 1500093582801 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49378/test.html"
21:39:42 INFO - 1500093582804 Marionette TRACE 358 <- [1,4,null,{}]
21:39:42 INFO - 1500093582805 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:39:42 INFO - 1500093582815 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"12ed9201-234c-4bc6-bd36-3123ef90c4eb","ELEMENT":"12ed9201-234c-4bc6-bd36-3123ef90c4eb"}}]
21:39:42 INFO - 1500093582817 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:39:42 INFO - 1500093582818 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:39:42 INFO - 1500093582820 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}]
21:39:42 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500093582822 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:39:42 INFO - 1500093582823 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:39:42 INFO - 1500093582824 Marionette TRACE 358 <- [1,8,null,{}]
21:39:42 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 195ms
21:39:42 INFO - TEST-START | test_click.py TestClick.test_container_element
21:39:42 INFO - 1500093582826 Marionette DEBUG Closed connection 358
21:39:42 INFO - 1500093582827 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49820
21:39:42 INFO - 1500093582829 Marionette DEBUG Closed connection 359
21:39:42 INFO - 1500093582829 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49821
21:39:42 INFO - 1500093582830 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:42 INFO - 1500093582831 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:42 INFO - 1500093582833 Marionette DEBUG Register listener.js for window 15
21:39:42 INFO - 1500093582836 Marionette TRACE 360 <- [1,1,null,{"sessionId":"42b85efb-b2b3-4532-9772-e7a0c3775d62","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:42 INFO - 1500093582838 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:39:42 INFO - 1500093582839 Marionette TRACE 360 <- [1,2,null,{}]
21:39:42 INFO - 1500093582840 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:42 INFO - 1500093582840 Marionette TRACE 360 <- [1,3,null,{}]
21:39:42 INFO - 1500093582841 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:42 INFO - 1500093582841 Marionette TRACE 360 <- [1,4,null,{}]
21:39:42 INFO - 1500093582843 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}]
21:39:42 INFO - TEST-START: test_click.py TestClick.test_container_element1500093582845 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:39:42 INFO - 1500093582846 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:39:42 INFO - 1500093582848 Marionette TRACE 360 <- [1,6,null,{}]
21:39:42 INFO - 1500093582849 Marionette DEBUG Closed connection 360
21:39:42 INFO - 1500093582850 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49822
21:39:42 INFO - 1500093582850 Marionette DEBUG Closed connection 361
21:39:42 INFO - 1500093582851 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49823
21:39:42 INFO - 1500093582852 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:42 INFO - 1500093582853 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:42 INFO - 1500093582855 Marionette DEBUG Register listener.js for window 15
21:39:42 INFO - 1500093582857 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d9aac6c4-21cb-4edc-80ea-46e08aa089e5","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:42 INFO - 1500093582859 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:39:42 INFO - 1500093582860 Marionette TRACE 362 <- [1,2,null,{}]
21:39:42 INFO - 1500093582862 Marionette DEBUG Closed connection 362
21:39:42 INFO - 1500093582863 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49824
21:39:42 INFO - 1500093582863 Marionette DEBUG Closed connection 363
21:39:42 INFO - 1500093582864 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49825
21:39:42 INFO - 1500093582865 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:42 INFO - 1500093582866 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:42 INFO - 1500093582868 Marionette DEBUG Register listener.js for window 15
21:39:42 INFO - 1500093582871 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9b1333ae-dd05-44fd-a249-c48e90775efd","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:42 INFO - 1500093582873 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"}]
21:39:42 INFO - 1500093582874 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49378/test.html"
21:39:42 INFO - 1500093582878 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49378/test.html"
21:39:42 INFO - 1500093582878 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49378/test.html"
21:39:42 INFO - 1500093582908 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"
21:39:42 INFO - 1500093582916 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"
21:39:42 INFO - 1500093582920 Marionette TRACE 364 <- [1,2,null,{}]
21:39:42 INFO - 1500093582921 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:39:42 INFO - 1500093582924 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8048f32c-be66-48f8-a1a3-f080c9650b0d","ELEMENT":"8048f32c-be66-48f8-a1a3-f080c9650b0d"}}]
21:39:42 INFO - 1500093582924 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8048f32c-be66-48f8-a1a3-f080c9650b0d"}]
21:39:43 INFO - 1500093583170 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:43 INFO - 1500093583171 Marionette TRACE 364 <- [1,4,null,{}]
21:39:43 INFO - 1500093583173 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8048f32c-be66-48f8-a1a3-f080c9650b0d","name":"selected"}]
21:39:43 INFO - 1500093583177 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:39:43 INFO - 1500093583179 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}]
21:39:43 INFO - TEST-END: test_click.py TestClick.test_container_element1500093583182 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:39:43 INFO - 1500093583182 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:39:43 INFO - 1500093583184 Marionette TRACE 364 <- [1,7,null,{}]
21:39:43 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 359ms
21:39:43 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:39:43 INFO - 1500093583186 Marionette DEBUG Closed connection 364
21:39:43 INFO - 1500093583187 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49826
21:39:43 INFO - 1500093583187 Marionette DEBUG Closed connection 365
21:39:43 INFO - 1500093583188 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49827
21:39:43 INFO - 1500093583189 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:43 INFO - 1500093583190 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583192 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583195 Marionette TRACE 366 <- [1,1,null,{"sessionId":"313b28fb-808a-4763-a839-7ebe87a0cf04","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583204 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:39:43 INFO - 1500093583204 Marionette TRACE 366 <- [1,2,null,{}]
21:39:43 INFO - 1500093583207 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:43 INFO - 1500093583207 Marionette TRACE 366 <- [1,3,null,{}]
21:39:43 INFO - 1500093583257 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:43 INFO - 1500093583258 Marionette TRACE 366 <- [1,4,null,{}]
21:39:43 INFO - 1500093583274 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}]
21:39:43 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500093583277 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:39:43 INFO - 1500093583278 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:39:43 INFO - 1500093583279 Marionette TRACE 366 <- [1,6,null,{}]
21:39:43 INFO - 1500093583281 Marionette DEBUG Closed connection 366
21:39:43 INFO - 1500093583282 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49828
21:39:43 INFO - 1500093583283 Marionette DEBUG Closed connection 367
21:39:43 INFO - 1500093583283 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49829
21:39:43 INFO - 1500093583284 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:43 INFO - 1500093583285 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583287 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583290 Marionette TRACE 368 <- [1,1,null,{"sessionId":"3394fe88-db18-4ed6-9ce1-b7da7248b3b6","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583292 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:39:43 INFO - 1500093583293 Marionette TRACE 368 <- [1,2,null,{}]
21:39:43 INFO - 1500093583295 Marionette DEBUG Closed connection 368
21:39:43 INFO - 1500093583296 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49830
21:39:43 INFO - 1500093583296 Marionette DEBUG Closed connection 369
21:39:43 INFO - 1500093583297 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49831
21:39:43 INFO - 1500093583298 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:43 INFO - 1500093583299 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583301 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583304 Marionette TRACE 370 <- [1,1,null,{"sessionId":"97889d83-d4a7-4213-8ddf-67e9181ecbd3","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583306 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"}]
21:39:43 INFO - 1500093583307 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"
21:39:43 INFO - 1500093583310 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"
21:39:43 INFO - 1500093583310 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"
21:39:43 INFO - 1500093583321 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"
21:39:43 INFO - 1500093583324 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"
21:39:43 INFO - 1500093583334 Marionette TRACE 370 <- [1,2,null,{}]
21:39:43 INFO - 1500093583336 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:39:43 INFO - 1500093583338 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"74622598-d17f-4576-85c9-15b02549432e","ELEMENT":"74622598-d17f-4576-85c9-15b02549432e"}}]
21:39:43 INFO - 1500093583339 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"74622598-d17f-4576-85c9-15b02549432e"}]
21:39:43 INFO - 1500093583585 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:43 INFO - 1500093583586 Marionette TRACE 370 <- [1,4,null,{}]
21:39:43 INFO - 1500093583587 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"74622598-d17f-4576-85c9-15b02549432e","name":"selected"}]
21:39:43 INFO - 1500093583591 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:39:43 INFO - 1500093583592 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}]
21:39:43 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500093583595 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:39:43 INFO - 1500093583596 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:39:43 INFO - 1500093583597 Marionette TRACE 370 <- [1,7,null,{}]
21:39:43 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 413ms
21:39:43 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:39:43 INFO - 1500093583599 Marionette DEBUG Closed connection 370
21:39:43 INFO - 1500093583600 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49832
21:39:43 INFO - 1500093583600 Marionette DEBUG Closed connection 371
21:39:43 INFO - 1500093583601 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49833
21:39:43 INFO - 1500093583602 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:43 INFO - 1500093583603 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583605 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583608 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ef9264d7-abe6-4028-8cbb-31a396497103","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583610 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:39:43 INFO - 1500093583610 Marionette TRACE 372 <- [1,2,null,{}]
21:39:43 INFO - 1500093583611 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:43 INFO - 1500093583611 Marionette TRACE 372 <- [1,3,null,{}]
21:39:43 INFO - 1500093583612 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:43 INFO - 1500093583613 Marionette TRACE 372 <- [1,4,null,{}]
21:39:43 INFO - 1500093583614 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}]
21:39:43 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500093583617 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:39:43 INFO - 1500093583617 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:39:43 INFO - 1500093583619 Marionette TRACE 372 <- [1,6,null,{}]
21:39:43 INFO - 1500093583620 Marionette DEBUG Closed connection 372
21:39:43 INFO - 1500093583621 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49834
21:39:43 INFO - 1500093583622 Marionette DEBUG Closed connection 373
21:39:43 INFO - 1500093583622 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49835
21:39:43 INFO - 1500093583623 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:43 INFO - 1500093583624 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583626 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583628 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a22891fb-22be-4768-b192-b9fb2a645ac6","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583630 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:39:43 INFO - 1500093583631 Marionette TRACE 374 <- [1,2,null,{}]
21:39:43 INFO - 1500093583633 Marionette DEBUG Closed connection 374
21:39:43 INFO - 1500093583634 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49836
21:39:43 INFO - 1500093583634 Marionette DEBUG Closed connection 375
21:39:43 INFO - 1500093583635 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49837
21:39:43 INFO - 1500093583636 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:43 INFO - 1500093583637 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583639 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583641 Marionette TRACE 376 <- [1,1,null,{"sessionId":"57da340a-ac9a-4298-9704-32c4902e7bb2","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583643 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"}]
21:39:43 INFO - 1500093583645 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"
21:39:43 INFO - 1500093583647 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"
21:39:43 INFO - 1500093583648 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"
21:39:43 INFO - 1500093583668 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"
21:39:43 INFO - 1500093583669 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"
21:39:43 INFO - 1500093583674 Marionette TRACE 376 <- [1,2,null,{}]
21:39:43 INFO - 1500093583682 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:39:43 INFO - 1500093583685 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4be0c4c0-c7ed-4689-b76f-1931c4d321ab","ELEMENT":"4be0c4c0-c7ed-4689-b76f-1931c4d321ab"}}]
21:39:43 INFO - 1500093583686 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"4be0c4c0-c7ed-4689-b76f-1931c4d321ab"}]
21:39:43 INFO - 1500093583925 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:43 INFO - 1500093583926 Marionette TRACE 376 <- [1,4,null,{}]
21:39:43 INFO - 1500093583927 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}]
21:39:43 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500093583930 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:39:43 INFO - 1500093583931 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:39:43 INFO - 1500093583932 Marionette TRACE 376 <- [1,6,null,{}]
21:39:43 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 335ms
21:39:43 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:39:43 INFO - 1500093583934 Marionette DEBUG Closed connection 376
21:39:43 INFO - 1500093583935 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49838
21:39:43 INFO - 1500093583936 Marionette DEBUG Closed connection 377
21:39:43 INFO - 1500093583937 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49839
21:39:43 INFO - 1500093583938 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:43 INFO - 1500093583938 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583940 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583943 Marionette TRACE 378 <- [1,1,null,{"sessionId":"8d8d9983-282b-4af0-9e9e-1c88f95fdcc5","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583945 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:39:43 INFO - 1500093583945 Marionette TRACE 378 <- [1,2,null,{}]
21:39:43 INFO - 1500093583946 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:43 INFO - 1500093583947 Marionette TRACE 378 <- [1,3,null,{}]
21:39:43 INFO - 1500093583947 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:43 INFO - 1500093583948 Marionette TRACE 378 <- [1,4,null,{}]
21:39:43 INFO - 1500093583949 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}]
21:39:43 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500093583952 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:39:43 INFO - 1500093583953 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:39:43 INFO - 1500093583954 Marionette TRACE 378 <- [1,6,null,{}]
21:39:43 INFO - 1500093583955 Marionette DEBUG Closed connection 378
21:39:43 INFO - 1500093583956 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49840
21:39:43 INFO - 1500093583957 Marionette DEBUG Closed connection 379
21:39:43 INFO - 1500093583958 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49841
21:39:43 INFO - 1500093583958 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:43 INFO - 1500093583959 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583961 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583971 Marionette TRACE 380 <- [1,1,null,{"sessionId":"928eff1b-85ae-467c-86c9-f757d8a25df7","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583972 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:39:43 INFO - 1500093583974 Marionette TRACE 380 <- [1,2,null,{}]
21:39:43 INFO - 1500093583976 Marionette DEBUG Closed connection 380
21:39:43 INFO - 1500093583976 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49842
21:39:43 INFO - 1500093583977 Marionette DEBUG Closed connection 381
21:39:43 INFO - 1500093583978 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49843
21:39:43 INFO - 1500093583979 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:43 INFO - 1500093583979 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:43 INFO - 1500093583981 Marionette DEBUG Register listener.js for window 15
21:39:43 INFO - 1500093583984 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c94dd6d7-bb47-4de6-b49a-9dd4cfd813b6","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:43 INFO - 1500093583986 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"}]
21:39:43 INFO - 1500093583987 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"
21:39:43 INFO - 1500093583989 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"
21:39:43 INFO - 1500093583989 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"
21:39:44 INFO - 1500093584008 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"
21:39:44 INFO - 1500093584014 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"
21:39:44 INFO - 1500093584017 Marionette TRACE 382 <- [1,2,null,{}]
21:39:44 INFO - 1500093584018 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:39:44 INFO - 1500093584021 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d543a86f-3fab-4319-9932-fabf34df506f","ELEMENT":"d543a86f-3fab-4319-9932-fabf34df506f"}}]
21:39:44 INFO - 1500093584022 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d543a86f-3fab-4319-9932-fabf34df506f"}]
21:39:44 INFO - 1500093584267 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:44 INFO - 1500093584267 Marionette TRACE 382 <- [1,4,null,{}]
21:39:44 INFO - 1500093584269 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}]
21:39:44 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500093584272 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:39:44 INFO - 1500093584273 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:39:44 INFO - 1500093584274 Marionette TRACE 382 <- [1,6,null,{}]
21:39:44 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 342ms
21:39:44 INFO - TEST-START | test_click.py TestClick.test_input_file
21:39:44 INFO - 1500093584276 Marionette DEBUG Closed connection 382
21:39:44 INFO - 1500093584278 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49844
21:39:44 INFO - 1500093584278 Marionette DEBUG Closed connection 383
21:39:44 INFO - 1500093584279 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49845
21:39:44 INFO - 1500093584280 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:44 INFO - 1500093584281 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584283 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584286 Marionette TRACE 384 <- [1,1,null,{"sessionId":"2a85a50c-ae04-4622-b17d-02343e936d9b","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584288 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:39:44 INFO - 1500093584288 Marionette TRACE 384 <- [1,2,null,{}]
21:39:44 INFO - 1500093584289 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:44 INFO - 1500093584290 Marionette TRACE 384 <- [1,3,null,{}]
21:39:44 INFO - 1500093584290 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:44 INFO - 1500093584291 Marionette TRACE 384 <- [1,4,null,{}]
21:39:44 INFO - 1500093584292 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}]
21:39:44 INFO - TEST-START: test_click.py TestClick.test_input_file1500093584295 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:39:44 INFO - 1500093584296 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:39:44 INFO - 1500093584297 Marionette TRACE 384 <- [1,6,null,{}]
21:39:44 INFO - 1500093584299 Marionette DEBUG Closed connection 384
21:39:44 INFO - 1500093584299 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49846
21:39:44 INFO - 1500093584300 Marionette DEBUG Closed connection 385
21:39:44 INFO - 1500093584301 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49847
21:39:44 INFO - 1500093584302 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:44 INFO - 1500093584302 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584304 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584307 Marionette TRACE 386 <- [1,1,null,{"sessionId":"bcf1d066-e0b6-4ac8-8190-961a2b914121","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584309 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:39:44 INFO - 1500093584310 Marionette TRACE 386 <- [1,2,null,{}]
21:39:44 INFO - 1500093584312 Marionette DEBUG Closed connection 386
21:39:44 INFO - 1500093584312 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49848
21:39:44 INFO - 1500093584313 Marionette DEBUG Closed connection 387
21:39:44 INFO - 1500093584314 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49849
21:39:44 INFO - 1500093584315 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:44 INFO - 1500093584316 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584318 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584321 Marionette TRACE 388 <- [1,1,null,{"sessionId":"8a251849-b471-4651-afb8-4ecdc140c615","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584323 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:39:44 INFO - 1500093584324 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"
21:39:44 INFO - 1500093584326 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"
21:39:44 INFO - 1500093584327 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"
21:39:44 INFO - 1500093584337 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:44 INFO - 1500093584340 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:44 INFO - 1500093584343 Marionette TRACE 388 <- [1,2,null,{}]
21:39:44 INFO - 1500093584353 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:39:44 INFO - 1500093584356 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"00269e5d-8cec-4ada-9a76-bac6b62d12f1","ELEMENT":"00269e5d-8cec-4ada-9a76-bac6b62d12f1"}}]
21:39:44 INFO - 1500093584357 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"00269e5d-8cec-4ada-9a76-bac6b62d12f1"}]
21:39:44 INFO - 1500093584385 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]
21:39:44 INFO - 1500093584387 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}]
21:39:44 INFO - TEST-END: test_click.py TestClick.test_input_file1500093584389 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:39:44 INFO - 1500093584390 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:39:44 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 117ms
21:39:44 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:39:44 INFO - 1500093584392 Marionette TRACE 388 <- [1,6,null,{}]
21:39:44 INFO - 1500093584394 Marionette DEBUG Closed connection 388
21:39:44 INFO - 1500093584395 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49850
21:39:44 INFO - 1500093584395 Marionette DEBUG Closed connection 389
21:39:44 INFO - 1500093584396 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49851
21:39:44 INFO - 1500093584397 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:44 INFO - 1500093584398 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584400 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584403 Marionette TRACE 390 <- [1,1,null,{"sessionId":"82ad86fe-c24c-4388-8379-1980d858ee27","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584404 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:39:44 INFO - 1500093584405 Marionette TRACE 390 <- [1,2,null,{}]
21:39:44 INFO - 1500093584406 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:44 INFO - 1500093584406 Marionette TRACE 390 <- [1,3,null,{}]
21:39:44 INFO - 1500093584407 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:44 INFO - 1500093584408 Marionette TRACE 390 <- [1,4,null,{}]
21:39:44 INFO - 1500093584409 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}]
21:39:44 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500093584412 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:39:44 INFO - 1500093584413 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:39:44 INFO - 1500093584414 Marionette TRACE 390 <- [1,6,null,{}]
21:39:44 INFO - 1500093584415 Marionette DEBUG Closed connection 390
21:39:44 INFO - 1500093584416 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49852
21:39:44 INFO - 1500093584417 Marionette DEBUG Closed connection 391
21:39:44 INFO - 1500093584418 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49853
21:39:44 INFO - 1500093584419 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:44 INFO - 1500093584419 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584421 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584424 Marionette TRACE 392 <- [1,1,null,{"sessionId":"3b4bdfbc-6aaa-4698-8b4e-2dbb6e7d61f8","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584426 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:39:44 INFO - 1500093584427 Marionette TRACE 392 <- [1,2,null,{}]
21:39:44 INFO - 1500093584429 Marionette DEBUG Closed connection 392
21:39:44 INFO - 1500093584430 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49854
21:39:44 INFO - 1500093584430 Marionette DEBUG Closed connection 393
21:39:44 INFO - 1500093584431 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49855
21:39:44 INFO - 1500093584432 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:44 INFO - 1500093584433 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584435 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584444 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c99f7ec2-7d40-43bc-83d9-aee77b1434b7","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584446 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"}]
21:39:44 INFO - 1500093584447 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:44 INFO - 1500093584450 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:44 INFO - 1500093584450 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:44 INFO - 1500093584469 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"
21:39:44 INFO - 1500093584471 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"
21:39:44 INFO - 1500093584473 Marionette TRACE 394 <- [1,2,null,{}]
21:39:44 INFO - 1500093584474 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:39:44 INFO - 1500093584483 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f44fb98-3a9b-4a64-96d9-9918c7ae496c","ELEMENT":"7f44fb98-3a9b-4a64-96d9-9918c7ae496c"}}]
21:39:44 INFO - 1500093584484 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:39:44 INFO - 1500093584486 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a074849d-f521-4813-a820-432e89f690ec","ELEMENT":"a074849d-f521-4813-a820-432e89f690ec"}}]
21:39:44 INFO - 1500093584487 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7f44fb98-3a9b-4a64-96d9-9918c7ae496c"}]
21:39:44 INFO - 1500093584722 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:44 INFO - 1500093584722 Marionette TRACE 394 <- [1,5,null,{}]
21:39:44 INFO - 1500093584724 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a074849d-f521-4813-a820-432e89f690ec"}]
21:39:44 INFO - 1500093584742 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]
21:39:44 INFO - 1500093584743 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:39:44 INFO - 1500093584746 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:39:44 INFO - 1500093584747 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}]
21:39:44 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500093584749 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:39:44 INFO - 1500093584750 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:39:44 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 359ms
21:39:44 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:39:44 INFO - 1500093584752 Marionette TRACE 394 <- [1,9,null,{}]
21:39:44 INFO - 1500093584753 Marionette DEBUG Closed connection 394
21:39:44 INFO - 1500093584754 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49856
21:39:44 INFO - 1500093584755 Marionette DEBUG Closed connection 395
21:39:44 INFO - 1500093584756 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49857
21:39:44 INFO - 1500093584757 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:44 INFO - 1500093584757 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584759 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584762 Marionette TRACE 396 <- [1,1,null,{"sessionId":"684eb06d-ceb7-47ce-bb1c-ffc50b9b4e0e","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584764 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:39:44 INFO - 1500093584765 Marionette TRACE 396 <- [1,2,null,{}]
21:39:44 INFO - 1500093584765 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:44 INFO - 1500093584766 Marionette TRACE 396 <- [1,3,null,{}]
21:39:44 INFO - 1500093584767 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:44 INFO - 1500093584767 Marionette TRACE 396 <- [1,4,null,{}]
21:39:44 INFO - 1500093584769 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}]
21:39:44 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500093584771 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:39:44 INFO - 1500093584772 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:39:44 INFO - 1500093584773 Marionette TRACE 396 <- [1,6,null,{}]
21:39:44 INFO - 1500093584775 Marionette DEBUG Closed connection 396
21:39:44 INFO - 1500093584775 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49858
21:39:44 INFO - 1500093584776 Marionette DEBUG Closed connection 397
21:39:44 INFO - 1500093584777 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49859
21:39:44 INFO - 1500093584778 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:44 INFO - 1500093584778 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584780 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584783 Marionette TRACE 398 <- [1,1,null,{"sessionId":"78f37fa1-d4f1-47a1-9f28-dffae816cb20","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584785 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:39:44 INFO - 1500093584786 Marionette TRACE 398 <- [1,2,null,{}]
21:39:44 INFO - 1500093584788 Marionette DEBUG Closed connection 398
21:39:44 INFO - 1500093584788 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49860
21:39:44 INFO - 1500093584789 Marionette DEBUG Closed connection 399
21:39:44 INFO - 1500093584790 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49861
21:39:44 INFO - 1500093584791 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:44 INFO - 1500093584791 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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}
21:39:44 INFO - 1500093584793 Marionette DEBUG Register listener.js for window 15
21:39:44 INFO - 1500093584798 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4c86573b-c3dc-448a-8918-cc935a10f4f2","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":736,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdffosn.mozrunner","moz:accessibilityChecks":false}}]
21:39:44 INFO - 1500093584801 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"}]
21:39:44 INFO - 1500093584803 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"
21:39:44 INFO - 1500093584805 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"
21:39:44 INFO - 1500093584805 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"
21:39:44 INFO - 1500093584822 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"
21:39:44 INFO - 1500093584824 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"
21:39:44 INFO - 1500093584827 Marionette TRACE 400 <- [1,2,null,{}]
21:39:44 INFO - 1500093584841 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:39:44 INFO - 1500093584844 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88a85a7a-5d6d-4953-818b-7067d65ec244","ELEMENT":"88a85a7a-5d6d-4953-818b-7067d65ec244"}}]
21:39:44 INFO - 1500093584845 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"88a85a7a-5d6d-4953-818b-7067d65ec244"}]
21:39:44 INFO - 1500093584867 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:39:44 INFO - 1500093584868 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"88a85a7a-5d6d-4953-818b-7067d65ec244"}]
21:39:44 INFO - 1500093584886 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element