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]
10:27:59 INFO - 1499362079765 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}]
10:27:59 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499362079769 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:27:59 INFO - 1499362079770 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:27:59 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 199ms
10:27:59 INFO - 1499362079772 Marionette TRACE 352 <- [1,6,null,{}]
10:27:59 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:27:59 INFO - 1499362079775 Marionette DEBUG Closed connection 352
10:27:59 INFO - 1499362079776 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49778
10:27:59 INFO - 1499362079777 Marionette DEBUG Closed connection 353
10:27:59 INFO - 1499362079777 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49779
10:27:59 INFO - 1499362079779 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:27:59 INFO - 1499362079779 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:27:59 INFO - 1499362079782 Marionette DEBUG Register listener.js for window 12
10:27:59 INFO - 1499362079786 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f8a83f60-d38e-4f2e-8790-449b2b022e5b","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:27:59 INFO - 1499362079787 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:27:59 INFO - 1499362079788 Marionette TRACE 354 <- [1,2,null,{}]
10:27:59 INFO - 1499362079789 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:27:59 INFO - 1499362079790 Marionette TRACE 354 <- [1,3,null,{}]
10:27:59 INFO - 1499362079791 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:27:59 INFO - 1499362079791 Marionette TRACE 354 <- [1,4,null,{}]
10:27:59 INFO - 1499362079793 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}]
10:27:59 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499362079797 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:27:59 INFO - 1499362079798 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:27:59 INFO - 1499362079800 Marionette TRACE 354 <- [1,6,null,{}]
10:27:59 INFO - 1499362079802 Marionette DEBUG Closed connection 354
10:27:59 INFO - 1499362079803 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49780
10:27:59 INFO - 1499362079804 Marionette DEBUG Closed connection 355
10:27:59 INFO - 1499362079805 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49781
10:27:59 INFO - 1499362079806 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:27:59 INFO - 1499362079806 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:27:59 INFO - 1499362079809 Marionette DEBUG Register listener.js for window 12
10:27:59 INFO - 1499362079813 Marionette TRACE 356 <- [1,1,null,{"sessionId":"fd67175b-1a59-4324-9ad1-ca97fa779fae","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:27:59 INFO - 1499362079815 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:27:59 INFO - 1499362079816 Marionette TRACE 356 <- [1,2,null,{}]
10:27:59 INFO - 1499362079818 Marionette DEBUG Closed connection 356
10:27:59 INFO - 1499362079819 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49782
10:27:59 INFO - 1499362079820 Marionette DEBUG Closed connection 357
10:27:59 INFO - 1499362079821 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49783
10:27:59 INFO - 1499362079822 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:27:59 INFO - 1499362079823 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:27:59 INFO - 1499362079826 Marionette DEBUG Register listener.js for window 12
10:27:59 INFO - 1499362079829 Marionette TRACE 358 <- [1,1,null,{"sessionId":"afa54db7-323e-48e2-89ce-2d7367f3c8c8","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:27:59 INFO - 1499362079831 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49349/clicks.html"}]
10:27:59 INFO - 1499362079832 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49349/hidden.html"
10:27:59 INFO - 1499362079839 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49349/hidden.html"
10:27:59 INFO - 1499362079839 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49349/hidden.html"
10:27:59 INFO - 1499362079868 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49349/clicks.html"
10:27:59 INFO - 1499362079870 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49349/clicks.html"
10:27:59 INFO - 1499362079874 Marionette TRACE 358 <- [1,2,null,{}]
10:27:59 INFO - 1499362079887 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:27:59 INFO - 1499362079890 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"383a7b1d-3e80-4c52-b070-6ba5ba97d2ff","ELEMENT":"383a7b1d-3e80-4c52-b070-6ba5ba97d2ff"}}]
10:27:59 INFO - 1499362079891 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"383a7b1d-3e80-4c52-b070-6ba5ba97d2ff"}]
10:27:59 INFO - 1499362079946 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49349/clicks.html"
10:27:59 INFO - 1499362079954 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49349/clicks.html"
10:27:59 INFO - 1499362079955 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49349/clicks.html"
10:27:59 INFO - 1499362079989 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49349/test.html"
10:27:59 INFO - 1499362079997 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49349/test.html"
10:28:00 INFO - 1499362080010 Marionette TRACE 358 <- [1,4,null,{}]
10:28:00 INFO - 1499362080015 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:28:00 INFO - 1499362080018 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ec9a267-f00f-4b0b-9dd1-2a26f37a3beb","ELEMENT":"8ec9a267-f00f-4b0b-9dd1-2a26f37a3beb"}}]
10:28:00 INFO - 1499362080024 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:28:00 INFO - 1499362080025 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:28:00 INFO - 1499362080027 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}]
10:28:00 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499362080030 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:28:00 INFO - 1499362080031 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:28:00 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 260ms
10:28:00 INFO - 1499362080033 Marionette TRACE 358 <- [1,8,null,{}]
10:28:00 INFO - TEST-START | test_click.py TestClick.test_container_element
10:28:00 INFO - 1499362080036 Marionette DEBUG Closed connection 358
10:28:00 INFO - 1499362080037 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49786
10:28:00 INFO - 1499362080037 Marionette DEBUG Closed connection 359
10:28:00 INFO - 1499362080038 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49787
10:28:00 INFO - 1499362080039 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:00 INFO - 1499362080040 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080043 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080051 Marionette TRACE 360 <- [1,1,null,{"sessionId":"9a3103ae-598b-4f56-8766-030cd7aa41dc","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080052 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:28:00 INFO - 1499362080053 Marionette TRACE 360 <- [1,2,null,{}]
10:28:00 INFO - 1499362080054 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:28:00 INFO - 1499362080055 Marionette TRACE 360 <- [1,3,null,{}]
10:28:00 INFO - 1499362080055 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:28:00 INFO - 1499362080056 Marionette TRACE 360 <- [1,4,null,{}]
10:28:00 INFO - 1499362080059 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}]
10:28:00 INFO - TEST-START: test_click.py TestClick.test_container_element1499362080062 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:28:00 INFO - 1499362080063 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:28:00 INFO - 1499362080065 Marionette TRACE 360 <- [1,6,null,{}]
10:28:00 INFO - 1499362080068 Marionette DEBUG Closed connection 360
10:28:00 INFO - 1499362080068 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49788
10:28:00 INFO - 1499362080071 Marionette DEBUG Closed connection 361
10:28:00 INFO - 1499362080072 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49789
10:28:00 INFO - 1499362080073 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:00 INFO - 1499362080074 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080077 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080083 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1bbb630e-c4a9-4b45-960c-bd31083e0211","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080085 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:28:00 INFO - 1499362080086 Marionette TRACE 362 <- [1,2,null,{}]
10:28:00 INFO - 1499362080089 Marionette DEBUG Closed connection 362
10:28:00 INFO - 1499362080090 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49790
10:28:00 INFO - 1499362080094 Marionette DEBUG Closed connection 363
10:28:00 INFO - 1499362080095 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49791
10:28:00 INFO - 1499362080096 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:28:00 INFO - 1499362080097 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080100 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080104 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d3b559d0-cf8b-442c-84fd-161acfa89d03","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080105 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"}]
10:28:00 INFO - 1499362080107 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49349/test.html"
10:28:00 INFO - 1499362080115 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49349/test.html"
10:28:00 INFO - 1499362080115 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49349/test.html"
10:28:00 INFO - 1499362080139 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"
10:28:00 INFO - 1499362080148 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"
10:28:00 INFO - 1499362080152 Marionette TRACE 364 <- [1,2,null,{}]
10:28:00 INFO - 1499362080154 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:28:00 INFO - 1499362080157 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6cc4c08a-4281-4baf-86cc-dc78b330aa25","ELEMENT":"6cc4c08a-4281-4baf-86cc-dc78b330aa25"}}]
10:28:00 INFO - 1499362080166 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6cc4c08a-4281-4baf-86cc-dc78b330aa25"}]
10:28:00 INFO - 1499362080412 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:28:00 INFO - 1499362080413 Marionette TRACE 364 <- [1,4,null,{}]
10:28:00 INFO - 1499362080414 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6cc4c08a-4281-4baf-86cc-dc78b330aa25","name":"selected"}]
10:28:00 INFO - 1499362080419 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:28:00 INFO - 1499362080421 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}]
10:28:00 INFO - TEST-END: test_click.py TestClick.test_container_element1499362080424 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:28:00 INFO - 1499362080425 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:28:00 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 393ms
10:28:00 INFO - 1499362080427 Marionette TRACE 364 <- [1,7,null,{}]
10:28:00 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:28:00 INFO - 1499362080429 Marionette DEBUG Closed connection 364
10:28:00 INFO - 1499362080431 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49792
10:28:00 INFO - 1499362080432 Marionette DEBUG Closed connection 365
10:28:00 INFO - 1499362080432 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49793
10:28:00 INFO - 1499362080434 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:00 INFO - 1499362080434 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080437 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080441 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1fea2da5-ab5c-4188-8b4c-d53d2a150c5d","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080443 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:28:00 INFO - 1499362080443 Marionette TRACE 366 <- [1,2,null,{}]
10:28:00 INFO - 1499362080444 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:28:00 INFO - 1499362080445 Marionette TRACE 366 <- [1,3,null,{}]
10:28:00 INFO - 1499362080446 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:28:00 INFO - 1499362080446 Marionette TRACE 366 <- [1,4,null,{}]
10:28:00 INFO - 1499362080448 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}]
10:28:00 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499362080451 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:28:00 INFO - 1499362080453 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:28:00 INFO - 1499362080454 Marionette TRACE 366 <- [1,6,null,{}]
10:28:00 INFO - 1499362080456 Marionette DEBUG Closed connection 366
10:28:00 INFO - 1499362080457 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49794
10:28:00 INFO - 1499362080458 Marionette DEBUG Closed connection 367
10:28:00 INFO - 1499362080459 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49795
10:28:00 INFO - 1499362080460 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:00 INFO - 1499362080461 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080464 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080467 Marionette TRACE 368 <- [1,1,null,{"sessionId":"41a5546d-fed8-4691-93bf-4967e77f9cc0","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080469 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:28:00 INFO - 1499362080470 Marionette TRACE 368 <- [1,2,null,{}]
10:28:00 INFO - 1499362080472 Marionette DEBUG Closed connection 368
10:28:00 INFO - 1499362080473 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49796
10:28:00 INFO - 1499362080474 Marionette DEBUG Closed connection 369
10:28:00 INFO - 1499362080475 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49797
10:28:00 INFO - 1499362080476 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:28:00 INFO - 1499362080477 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080479 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080483 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7b7d49e5-2c5f-4295-80a1-0e00994cd662","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080484 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"}]
10:28:00 INFO - 1499362080487 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"
10:28:00 INFO - 1499362080491 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"
10:28:00 INFO - 1499362080491 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"
10:28:00 INFO - 1499362080512 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"
10:28:00 INFO - 1499362080517 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"
10:28:00 INFO - 1499362080521 Marionette TRACE 370 <- [1,2,null,{}]
10:28:00 INFO - 1499362080523 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:28:00 INFO - 1499362080533 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"140f8f53-f084-4f03-b657-1ca6c965dc4a","ELEMENT":"140f8f53-f084-4f03-b657-1ca6c965dc4a"}}]
10:28:00 INFO - 1499362080537 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"140f8f53-f084-4f03-b657-1ca6c965dc4a"}]
10:28:00 INFO - 1499362080795 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:28:00 INFO - 1499362080796 Marionette TRACE 370 <- [1,4,null,{}]
10:28:00 INFO - 1499362080797 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"140f8f53-f084-4f03-b657-1ca6c965dc4a","name":"selected"}]
10:28:00 INFO - 1499362080802 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:28:00 INFO - 1499362080804 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}]
10:28:00 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499362080807 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:28:00 INFO - 1499362080808 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:28:00 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 382ms
10:28:00 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:28:00 INFO - 1499362080810 Marionette TRACE 370 <- [1,7,null,{}]
10:28:00 INFO - 1499362080812 Marionette DEBUG Closed connection 370
10:28:00 INFO - 1499362080814 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49798
10:28:00 INFO - 1499362080815 Marionette DEBUG Closed connection 371
10:28:00 INFO - 1499362080815 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49799
10:28:00 INFO - 1499362080817 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:00 INFO - 1499362080817 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080820 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080832 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8db6d910-5c26-4767-ab5a-47f996820ba0","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080883 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:28:00 INFO - 1499362080884 Marionette TRACE 372 <- [1,2,null,{}]
10:28:00 INFO - 1499362080907 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:28:00 INFO - 1499362080907 Marionette TRACE 372 <- [1,3,null,{}]
10:28:00 INFO - 1499362080921 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:28:00 INFO - 1499362080921 Marionette TRACE 372 <- [1,4,null,{}]
10:28:00 INFO - 1499362080924 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}]
10:28:00 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499362080930 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:28:00 INFO - 1499362080931 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:28:00 INFO - 1499362080933 Marionette TRACE 372 <- [1,6,null,{}]
10:28:00 INFO - 1499362080935 Marionette DEBUG Closed connection 372
10:28:00 INFO - 1499362080936 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49800
10:28:00 INFO - 1499362080937 Marionette DEBUG Closed connection 373
10:28:00 INFO - 1499362080938 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49801
10:28:00 INFO - 1499362080939 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:00 INFO - 1499362080940 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080943 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080947 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c546ecc9-3d31-42a6-9855-7ba8fa1fe9bf","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080948 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:28:00 INFO - 1499362080949 Marionette TRACE 374 <- [1,2,null,{}]
10:28:00 INFO - 1499362080952 Marionette DEBUG Closed connection 374
10:28:00 INFO - 1499362080953 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49802
10:28:00 INFO - 1499362080954 Marionette DEBUG Closed connection 375
10:28:00 INFO - 1499362080954 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49803
10:28:00 INFO - 1499362080956 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:28:00 INFO - 1499362080956 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:00 INFO - 1499362080959 Marionette DEBUG Register listener.js for window 12
10:28:00 INFO - 1499362080963 Marionette TRACE 376 <- [1,1,null,{"sessionId":"cab4334b-cc8b-4e36-aa84-92479f74346b","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:00 INFO - 1499362080964 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"}]
10:28:00 INFO - 1499362080967 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"
10:28:00 INFO - 1499362080969 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"
10:28:00 INFO - 1499362080970 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"
10:28:00 INFO - 1499362080991 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"
10:28:01 INFO - 1499362080995 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"
10:28:01 INFO - 1499362081008 Marionette TRACE 376 <- [1,2,null,{}]
10:28:01 INFO - 1499362081011 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:28:01 INFO - 1499362081014 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63f80c07-8e61-4805-aafd-70ee8c88dc6a","ELEMENT":"63f80c07-8e61-4805-aafd-70ee8c88dc6a"}}]
10:28:01 INFO - 1499362081015 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"63f80c07-8e61-4805-aafd-70ee8c88dc6a"}]
10:28:01 INFO - 1499362081258 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:28:01 INFO - 1499362081259 Marionette TRACE 376 <- [1,4,null,{}]
10:28:01 INFO - 1499362081261 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}]
10:28:01 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499362081264 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:28:01 INFO - 1499362081265 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:28:01 INFO - 1499362081266 Marionette TRACE 376 <- [1,6,null,{}]
10:28:01 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 457ms
10:28:01 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:28:01 INFO - 1499362081269 Marionette DEBUG Closed connection 376
10:28:01 INFO - 1499362081270 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49804
10:28:01 INFO - 1499362081271 Marionette DEBUG Closed connection 377
10:28:01 INFO - 1499362081272 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49805
10:28:01 INFO - 1499362081273 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:01 INFO - 1499362081273 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081276 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081280 Marionette TRACE 378 <- [1,1,null,{"sessionId":"97941f8f-ae7b-45e8-a087-2e3449e3b388","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081282 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:28:01 INFO - 1499362081282 Marionette TRACE 378 <- [1,2,null,{}]
10:28:01 INFO - 1499362081283 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:28:01 INFO - 1499362081284 Marionette TRACE 378 <- [1,3,null,{}]
10:28:01 INFO - 1499362081285 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:28:01 INFO - 1499362081285 Marionette TRACE 378 <- [1,4,null,{}]
10:28:01 INFO - 1499362081287 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}]
10:28:01 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499362081290 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:28:01 INFO - 1499362081291 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:28:01 INFO - 1499362081293 Marionette TRACE 378 <- [1,6,null,{}]
10:28:01 INFO - 1499362081295 Marionette DEBUG Closed connection 378
10:28:01 INFO - 1499362081296 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49806
10:28:01 INFO - 1499362081297 Marionette DEBUG Closed connection 379
10:28:01 INFO - 1499362081298 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49807
10:28:01 INFO - 1499362081299 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:01 INFO - 1499362081300 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081302 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081306 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b332cd6a-7b82-438f-85c7-b7065b3d4edc","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081307 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:28:01 INFO - 1499362081309 Marionette TRACE 380 <- [1,2,null,{}]
10:28:01 INFO - 1499362081311 Marionette DEBUG Closed connection 380
10:28:01 INFO - 1499362081312 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49808
10:28:01 INFO - 1499362081313 Marionette DEBUG Closed connection 381
10:28:01 INFO - 1499362081314 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49809
10:28:01 INFO - 1499362081315 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:28:01 INFO - 1499362081316 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081318 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081322 Marionette TRACE 382 <- [1,1,null,{"sessionId":"223cf3b6-860b-4974-b024-2ab9d8b1089e","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081323 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"}]
10:28:01 INFO - 1499362081325 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"
10:28:01 INFO - 1499362081328 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"
10:28:01 INFO - 1499362081328 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"
10:28:01 INFO - 1499362081351 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"
10:28:01 INFO - 1499362081354 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"
10:28:01 INFO - 1499362081358 Marionette TRACE 382 <- [1,2,null,{}]
10:28:01 INFO - 1499362081367 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:28:01 INFO - 1499362081371 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4a0f353-42ef-44ca-a0c5-1af60952ca87","ELEMENT":"c4a0f353-42ef-44ca-a0c5-1af60952ca87"}}]
10:28:01 INFO - 1499362081372 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c4a0f353-42ef-44ca-a0c5-1af60952ca87"}]
10:28:01 INFO - 1499362081625 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:28:01 INFO - 1499362081626 Marionette TRACE 382 <- [1,4,null,{}]
10:28:01 INFO - 1499362081628 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}]
10:28:01 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499362081631 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:28:01 INFO - 1499362081632 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:28:01 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 367ms
10:28:01 INFO - TEST-START | test_click.py TestClick.test_input_file
10:28:01 INFO - 1499362081634 Marionette TRACE 382 <- [1,6,null,{}]
10:28:01 INFO - 1499362081636 Marionette DEBUG Closed connection 382
10:28:01 INFO - 1499362081637 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49810
10:28:01 INFO - 1499362081638 Marionette DEBUG Closed connection 383
10:28:01 INFO - 1499362081639 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49811
10:28:01 INFO - 1499362081640 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:01 INFO - 1499362081640 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081643 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081647 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a6591216-94f3-4f73-815f-3296112a7fdc","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081648 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:28:01 INFO - 1499362081649 Marionette TRACE 384 <- [1,2,null,{}]
10:28:01 INFO - 1499362081650 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:28:01 INFO - 1499362081650 Marionette TRACE 384 <- [1,3,null,{}]
10:28:01 INFO - 1499362081652 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:28:01 INFO - 1499362081652 Marionette TRACE 384 <- [1,4,null,{}]
10:28:01 INFO - 1499362081654 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}]
10:28:01 INFO - TEST-START: test_click.py TestClick.test_input_file1499362081657 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:28:01 INFO - 1499362081658 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:28:01 INFO - 1499362081660 Marionette TRACE 384 <- [1,6,null,{}]
10:28:01 INFO - 1499362081662 Marionette DEBUG Closed connection 384
10:28:01 INFO - 1499362081663 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49812
10:28:01 INFO - 1499362081664 Marionette DEBUG Closed connection 385
10:28:01 INFO - 1499362081665 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49813
10:28:01 INFO - 1499362081666 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:01 INFO - 1499362081667 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081669 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081673 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b8880b60-56bc-464c-a685-e7557218c060","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081675 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:28:01 INFO - 1499362081676 Marionette TRACE 386 <- [1,2,null,{}]
10:28:01 INFO - 1499362081679 Marionette DEBUG Closed connection 386
10:28:01 INFO - 1499362081679 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49814
10:28:01 INFO - 1499362081680 Marionette DEBUG Closed connection 387
10:28:01 INFO - 1499362081681 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49815
10:28:01 INFO - 1499362081682 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:28:01 INFO - 1499362081683 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081686 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081690 Marionette TRACE 388 <- [1,1,null,{"sessionId":"dbb422dd-ceb6-44cc-835e-fb311b2d77a1","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081691 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:28:01 INFO - 1499362081693 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"
10:28:01 INFO - 1499362081696 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"
10:28:01 INFO - 1499362081697 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"
10:28:01 INFO - 1499362081720 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:28:01 INFO - 1499362081722 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:28:01 INFO - 1499362081727 Marionette TRACE 388 <- [1,2,null,{}]
10:28:01 INFO - 1499362081736 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:28:01 INFO - 1499362081740 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d4ea3ca-260c-4432-bdb1-db9a88155877","ELEMENT":"8d4ea3ca-260c-4432-bdb1-db9a88155877"}}]
10:28:01 INFO - 1499362081743 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"8d4ea3ca-260c-4432-bdb1-db9a88155877"}]
10:28:01 INFO - 1499362081773 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]
10:28:01 INFO - 1499362081775 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}]
10:28:01 INFO - TEST-END: test_click.py TestClick.test_input_file1499362081778 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:28:01 INFO - 1499362081779 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:28:01 INFO - 1499362081781 Marionette TRACE 388 <- [1,6,null,{}]
10:28:01 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 147ms
10:28:01 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:28:01 INFO - 1499362081783 Marionette DEBUG Closed connection 388
10:28:01 INFO - 1499362081784 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49816
10:28:01 INFO - 1499362081785 Marionette DEBUG Closed connection 389
10:28:01 INFO - 1499362081786 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49817
10:28:01 INFO - 1499362081787 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:01 INFO - 1499362081788 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081791 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081795 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c2956120-adcf-4e86-8640-346e27b0ecc6","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081796 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:28:01 INFO - 1499362081797 Marionette TRACE 390 <- [1,2,null,{}]
10:28:01 INFO - 1499362081798 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:28:01 INFO - 1499362081798 Marionette TRACE 390 <- [1,3,null,{}]
10:28:01 INFO - 1499362081800 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:28:01 INFO - 1499362081800 Marionette TRACE 390 <- [1,4,null,{}]
10:28:01 INFO - 1499362081803 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}]
10:28:01 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499362081806 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:28:01 INFO - 1499362081807 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:28:01 INFO - 1499362081809 Marionette TRACE 390 <- [1,6,null,{}]
10:28:01 INFO - 1499362081811 Marionette DEBUG Closed connection 390
10:28:01 INFO - 1499362081812 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49818
10:28:01 INFO - 1499362081813 Marionette DEBUG Closed connection 391
10:28:01 INFO - 1499362081814 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49819
10:28:01 INFO - 1499362081815 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:01 INFO - 1499362081816 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081818 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081822 Marionette TRACE 392 <- [1,1,null,{"sessionId":"1641eea6-3be5-48bb-997c-bd657f0659ec","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081824 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:28:01 INFO - 1499362081826 Marionette TRACE 392 <- [1,2,null,{}]
10:28:01 INFO - 1499362081828 Marionette DEBUG Closed connection 392
10:28:01 INFO - 1499362081829 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49820
10:28:01 INFO - 1499362081830 Marionette DEBUG Closed connection 393
10:28:01 INFO - 1499362081831 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49821
10:28:01 INFO - 1499362081832 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:28:01 INFO - 1499362081832 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:01 INFO - 1499362081835 Marionette DEBUG Register listener.js for window 12
10:28:01 INFO - 1499362081839 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e97be04e-95ca-4f56-a737-6728820f6516","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:01 INFO - 1499362081841 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"}]
10:28:01 INFO - 1499362081842 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:28:01 INFO - 1499362081845 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:28:01 INFO - 1499362081846 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:28:01 INFO - 1499362081871 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"
10:28:01 INFO - 1499362081873 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"
10:28:01 INFO - 1499362081877 Marionette TRACE 394 <- [1,2,null,{}]
10:28:01 INFO - 1499362081886 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:28:01 INFO - 1499362081889 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"989afaae-3e60-4f4e-b19f-1072080f5a2d","ELEMENT":"989afaae-3e60-4f4e-b19f-1072080f5a2d"}}]
10:28:01 INFO - 1499362081890 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:28:01 INFO - 1499362081894 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d43a486b-aa7d-4e91-a562-80de15e03ffc","ELEMENT":"d43a486b-aa7d-4e91-a562-80de15e03ffc"}}]
10:28:01 INFO - 1499362081896 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"989afaae-3e60-4f4e-b19f-1072080f5a2d"}]
10:28:02 INFO - 1499362082137 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:28:02 INFO - 1499362082138 Marionette TRACE 394 <- [1,5,null,{}]
10:28:02 INFO - 1499362082139 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d43a486b-aa7d-4e91-a562-80de15e03ffc"}]
10:28:02 INFO - 1499362082157 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]
10:28:02 INFO - 1499362082159 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:28:02 INFO - 1499362082162 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:28:02 INFO - 1499362082164 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}]
10:28:02 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499362082167 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:28:02 INFO - 1499362082168 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:28:02 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 389ms
10:28:02 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:28:02 INFO - 1499362082170 Marionette TRACE 394 <- [1,9,null,{}]
10:28:02 INFO - 1499362082172 Marionette DEBUG Closed connection 394
10:28:02 INFO - 1499362082173 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49822
10:28:02 INFO - 1499362082174 Marionette DEBUG Closed connection 395
10:28:02 INFO - 1499362082175 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49823
10:28:02 INFO - 1499362082176 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:02 INFO - 1499362082184 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:02 INFO - 1499362082187 Marionette DEBUG Register listener.js for window 12
10:28:02 INFO - 1499362082191 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8970c19a-3e48-4272-8e43-a3b1da63af65","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:02 INFO - 1499362082193 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:28:02 INFO - 1499362082193 Marionette TRACE 396 <- [1,2,null,{}]
10:28:02 INFO - 1499362082194 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:28:02 INFO - 1499362082195 Marionette TRACE 396 <- [1,3,null,{}]
10:28:02 INFO - 1499362082196 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:28:02 INFO - 1499362082196 Marionette TRACE 396 <- [1,4,null,{}]
10:28:02 INFO - 1499362082198 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}]
10:28:02 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499362082201 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:28:02 INFO - 1499362082202 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:28:02 INFO - 1499362082204 Marionette TRACE 396 <- [1,6,null,{}]
10:28:02 INFO - 1499362082206 Marionette DEBUG Closed connection 396
10:28:02 INFO - 1499362082207 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49824
10:28:02 INFO - 1499362082208 Marionette DEBUG Closed connection 397
10:28:02 INFO - 1499362082209 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49825
10:28:02 INFO - 1499362082211 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:28:02 INFO - 1499362082212 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:02 INFO - 1499362082215 Marionette DEBUG Register listener.js for window 12
10:28:02 INFO - 1499362082219 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1dd80a41-1dc1-4896-9f6e-0152cb5e7d04","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:02 INFO - 1499362082220 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:28:02 INFO - 1499362082221 Marionette TRACE 398 <- [1,2,null,{}]
10:28:02 INFO - 1499362082224 Marionette DEBUG Closed connection 398
10:28:02 INFO - 1499362082225 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49826
10:28:02 INFO - 1499362082226 Marionette DEBUG Closed connection 399
10:28:02 INFO - 1499362082227 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49827
10:28:02 INFO - 1499362082228 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:28:02 INFO - 1499362082228 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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}
10:28:02 INFO - 1499362082231 Marionette DEBUG Register listener.js for window 12
10:28:02 INFO - 1499362082235 Marionette TRACE 400 <- [1,1,null,{"sessionId":"5efc06cd-8e40-40ea-a33b-50e30226c439","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":1980,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpekyc3n.mozrunner","moz:accessibilityChecks":false}}]
10:28:02 INFO - 1499362082236 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"}]
10:28:02 INFO - 1499362082238 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"
10:28:02 INFO - 1499362082241 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"
10:28:02 INFO - 1499362082241 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"
10:28:02 INFO - 1499362082266 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"
10:28:02 INFO - 1499362082268 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"
10:28:02 INFO - 1499362082272 Marionette TRACE 400 <- [1,2,null,{}]
10:28:02 INFO - 1499362082273 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:28:02 INFO - 1499362082284 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ccd177d9-50c8-4cb8-8e59-5d1d28a47b8f","ELEMENT":"ccd177d9-50c8-4cb8-8e59-5d1d28a47b8f"}}]
10:28:02 INFO - 1499362082286 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"ccd177d9-50c8-4cb8-8e59-5d1d28a47b8f"}]
10:28:02 INFO - 1499362082312 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:28:02 INFO - 1499362082313 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"ccd177d9-50c8-4cb8-8e59-5d1d28a47b8f"}]
10:28:02 INFO - 1499362082336 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element