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]
15:50:23 INFO - 1499986223908 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}]
15:50:23 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499986223911 Marionette TRACE 352 <- [1,5,null,{"value":null}]
15:50:23 INFO - 1499986223911 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
15:50:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 139ms
15:50:23 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:50:23 INFO - 1499986223913 Marionette TRACE 352 <- [1,6,null,{}]
15:50:23 INFO - 1499986223914 Marionette DEBUG Closed connection 352
15:50:23 INFO - 1499986223915 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50265
15:50:23 INFO - 1499986223916 Marionette DEBUG Closed connection 353
15:50:23 INFO - 1499986223917 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50266
15:50:23 INFO - 1499986223918 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:23 INFO - 1499986223918 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:23 INFO - 1499986223920 Marionette DEBUG Register listener.js for window 15
15:50:23 INFO - 1499986223923 Marionette TRACE 354 <- [1,1,null,{"sessionId":"8e9366e6-4fd1-4dae-8608-70e34c1d423a","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:23 INFO - 1499986223925 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
15:50:23 INFO - 1499986223925 Marionette TRACE 354 <- [1,2,null,{}]
15:50:23 INFO - 1499986223926 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:50:23 INFO - 1499986223927 Marionette TRACE 354 <- [1,3,null,{}]
15:50:23 INFO - 1499986223928 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
15:50:23 INFO - 1499986223928 Marionette TRACE 354 <- [1,4,null,{}]
15:50:23 INFO - 1499986223930 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}]
15:50:23 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499986223932 Marionette TRACE 354 <- [1,5,null,{"value":null}]
15:50:23 INFO - 1499986223933 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
15:50:23 INFO - 1499986223934 Marionette TRACE 354 <- [1,6,null,{}]
15:50:23 INFO - 1499986223936 Marionette DEBUG Closed connection 354
15:50:23 INFO - 1499986223936 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50267
15:50:23 INFO - 1499986223937 Marionette DEBUG Closed connection 355
15:50:23 INFO - 1499986223938 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50268
15:50:23 INFO - 1499986223939 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:23 INFO - 1499986223939 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:23 INFO - 1499986223941 Marionette DEBUG Register listener.js for window 15
15:50:23 INFO - 1499986223943 Marionette TRACE 356 <- [1,1,null,{"sessionId":"bcd2ee07-d899-4500-a8c3-abef5a1f3679","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:23 INFO - 1499986223945 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
15:50:23 INFO - 1499986223946 Marionette TRACE 356 <- [1,2,null,{}]
15:50:23 INFO - 1499986223948 Marionette DEBUG Closed connection 356
15:50:23 INFO - 1499986223949 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50269
15:50:23 INFO - 1499986223949 Marionette DEBUG Closed connection 357
15:50:23 INFO - 1499986223950 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50270
15:50:23 INFO - 1499986223951 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:50:23 INFO - 1499986223952 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:23 INFO - 1499986223953 Marionette DEBUG Register listener.js for window 15
15:50:23 INFO - 1499986223956 Marionette TRACE 358 <- [1,1,null,{"sessionId":"54817991-2a36-4f1c-9f6e-ea3ee1d1ee99","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:23 INFO - 1499986223959 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49834/clicks.html"}]
15:50:23 INFO - 1499986223960 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49834/hidden.html"
15:50:23 INFO - 1499986223965 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49834/hidden.html"
15:50:23 INFO - 1499986223965 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49834/hidden.html"
15:50:23 INFO - 1499986223984 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49834/clicks.html"
15:50:23 INFO - 1499986223985 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49834/clicks.html"
15:50:23 INFO - 1499986223989 Marionette TRACE 358 <- [1,2,null,{}]
15:50:23 INFO - 1499986223990 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:50:24 INFO - 1499986224000 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f94493b-04e0-4cfb-b224-c2991f8f6b80","ELEMENT":"6f94493b-04e0-4cfb-b224-c2991f8f6b80"}}]
15:50:24 INFO - 1499986224001 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"6f94493b-04e0-4cfb-b224-c2991f8f6b80"}]
15:50:24 INFO - 1499986224038 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49834/clicks.html"
15:50:24 INFO - 1499986224046 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49834/clicks.html"
15:50:24 INFO - 1499986224047 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49834/clicks.html"
15:50:24 INFO - 1499986224065 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49834/test.html"
15:50:24 INFO - 1499986224066 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49834/test.html"
15:50:24 INFO - 1499986224069 Marionette TRACE 358 <- [1,4,null,{}]
15:50:24 INFO - 1499986224070 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:50:24 INFO - 1499986224072 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ad0ca80d-3d44-49b7-ac65-34ea3ad488da","ELEMENT":"ad0ca80d-3d44-49b7-ac65-34ea3ad488da"}}]
15:50:24 INFO - 1499986224073 Marionette TRACE 358 -> [0,6,"getTitle",{}]
15:50:24 INFO - 1499986224074 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
15:50:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 174ms
15:50:24 INFO - TEST-START | test_click.py TestClick.test_container_element
15:50:24 INFO - 1499986224082 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}]
15:50:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499986224084 Marionette TRACE 358 <- [1,7,null,{"value":null}]
15:50:24 INFO - 1499986224085 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
15:50:24 INFO - 1499986224086 Marionette TRACE 358 <- [1,8,null,{}]
15:50:24 INFO - 1499986224088 Marionette DEBUG Closed connection 358
15:50:24 INFO - 1499986224089 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50273
15:50:24 INFO - 1499986224090 Marionette DEBUG Closed connection 359
15:50:24 INFO - 1499986224091 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50274
15:50:24 INFO - 1499986224092 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:24 INFO - 1499986224094 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224096 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224107 Marionette TRACE 360 <- [1,1,null,{"sessionId":"ea80a62c-f921-47d3-a29a-03d14e3594f0","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224109 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
15:50:24 INFO - 1499986224110 Marionette TRACE 360 <- [1,2,null,{}]
15:50:24 INFO - 1499986224110 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:50:24 INFO - 1499986224111 Marionette TRACE 360 <- [1,3,null,{}]
15:50:24 INFO - 1499986224112 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
15:50:24 INFO - 1499986224112 Marionette TRACE 360 <- [1,4,null,{}]
15:50:24 INFO - 1499986224114 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}]
15:50:24 INFO - TEST-START: test_click.py TestClick.test_container_element1499986224116 Marionette TRACE 360 <- [1,5,null,{"value":null}]
15:50:24 INFO - 1499986224117 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
15:50:24 INFO - 1499986224118 Marionette TRACE 360 <- [1,6,null,{}]
15:50:24 INFO - 1499986224120 Marionette DEBUG Closed connection 360
15:50:24 INFO - 1499986224121 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50275
15:50:24 INFO - 1499986224122 Marionette DEBUG Closed connection 361
15:50:24 INFO - 1499986224122 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50276
15:50:24 INFO - 1499986224123 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:24 INFO - 1499986224124 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224126 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224128 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c7eb40c0-0082-48ba-a893-c6f3d8332783","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224130 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
15:50:24 INFO - 1499986224131 Marionette TRACE 362 <- [1,2,null,{}]
15:50:24 INFO - 1499986224133 Marionette DEBUG Closed connection 362
15:50:24 INFO - 1499986224133 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50277
15:50:24 INFO - 1499986224134 Marionette DEBUG Closed connection 363
15:50:24 INFO - 1499986224135 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50278
15:50:24 INFO - 1499986224136 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:50:24 INFO - 1499986224136 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224138 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224141 Marionette TRACE 364 <- [1,1,null,{"sessionId":"fcddce91-4bf9-4a22-baee-8a2a4f8df319","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224142 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"}]
15:50:24 INFO - 1499986224144 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49834/test.html"
15:50:24 INFO - 1499986224146 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49834/test.html"
15:50:24 INFO - 1499986224146 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49834/test.html"
15:50:24 INFO - 1499986224156 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"
15:50:24 INFO - 1499986224167 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"
15:50:24 INFO - 1499986224170 Marionette TRACE 364 <- [1,2,null,{}]
15:50:24 INFO - 1499986224171 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:50:24 INFO - 1499986224174 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1c314337-d58d-400a-96c7-e03d59665b04","ELEMENT":"1c314337-d58d-400a-96c7-e03d59665b04"}}]
15:50:24 INFO - 1499986224180 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"1c314337-d58d-400a-96c7-e03d59665b04"}]
15:50:24 INFO - 1499986224417 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:50:24 INFO - 1499986224417 Marionette TRACE 364 <- [1,4,null,{}]
15:50:24 INFO - 1499986224419 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"1c314337-d58d-400a-96c7-e03d59665b04","name":"selected"}]
15:50:24 INFO - 1499986224423 Marionette TRACE 364 <- [1,5,null,{"value":true}]
15:50:24 INFO - 1499986224424 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}]
15:50:24 INFO - TEST-END: test_click.py TestClick.test_container_element1499986224427 Marionette TRACE 364 <- [1,6,null,{"value":null}]
15:50:24 INFO - 1499986224427 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
15:50:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 342ms
15:50:24 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:50:24 INFO - 1499986224429 Marionette TRACE 364 <- [1,7,null,{}]
15:50:24 INFO - 1499986224430 Marionette DEBUG Closed connection 364
15:50:24 INFO - 1499986224432 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50279
15:50:24 INFO - 1499986224432 Marionette DEBUG Closed connection 365
15:50:24 INFO - 1499986224433 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50280
15:50:24 INFO - 1499986224434 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:24 INFO - 1499986224435 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224437 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224439 Marionette TRACE 366 <- [1,1,null,{"sessionId":"87b54d58-0f6c-4c2c-9560-fc3e2243d32e","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224442 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
15:50:24 INFO - 1499986224442 Marionette TRACE 366 <- [1,2,null,{}]
15:50:24 INFO - 1499986224443 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:50:24 INFO - 1499986224444 Marionette TRACE 366 <- [1,3,null,{}]
15:50:24 INFO - 1499986224444 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
15:50:24 INFO - 1499986224445 Marionette TRACE 366 <- [1,4,null,{}]
15:50:24 INFO - 1499986224446 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}]
15:50:24 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499986224449 Marionette TRACE 366 <- [1,5,null,{"value":null}]
15:50:24 INFO - 1499986224450 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
15:50:24 INFO - 1499986224451 Marionette TRACE 366 <- [1,6,null,{}]
15:50:24 INFO - 1499986224452 Marionette DEBUG Closed connection 366
15:50:24 INFO - 1499986224453 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50281
15:50:24 INFO - 1499986224454 Marionette DEBUG Closed connection 367
15:50:24 INFO - 1499986224454 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50282
15:50:24 INFO - 1499986224455 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:24 INFO - 1499986224456 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224457 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224460 Marionette TRACE 368 <- [1,1,null,{"sessionId":"84871581-4a56-4a4f-aba8-d4be0385eb43","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224462 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
15:50:24 INFO - 1499986224463 Marionette TRACE 368 <- [1,2,null,{}]
15:50:24 INFO - 1499986224465 Marionette DEBUG Closed connection 368
15:50:24 INFO - 1499986224465 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50283
15:50:24 INFO - 1499986224466 Marionette DEBUG Closed connection 369
15:50:24 INFO - 1499986224467 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50284
15:50:24 INFO - 1499986224468 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:50:24 INFO - 1499986224468 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224470 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224472 Marionette TRACE 370 <- [1,1,null,{"sessionId":"35bb15ea-7cc8-4cf1-b505-6ea8d9e51e7f","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224474 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"}]
15:50:24 INFO - 1499986224475 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"
15:50:24 INFO - 1499986224477 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"
15:50:24 INFO - 1499986224478 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"
15:50:24 INFO - 1499986224488 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"
15:50:24 INFO - 1499986224491 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"
15:50:24 INFO - 1499986224500 Marionette TRACE 370 <- [1,2,null,{}]
15:50:24 INFO - 1499986224501 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:50:24 INFO - 1499986224505 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce75b55f-a254-446a-913c-29ddeae06480","ELEMENT":"ce75b55f-a254-446a-913c-29ddeae06480"}}]
15:50:24 INFO - 1499986224506 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ce75b55f-a254-446a-913c-29ddeae06480"}]
15:50:24 INFO - 1499986224743 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:50:24 INFO - 1499986224743 Marionette TRACE 370 <- [1,4,null,{}]
15:50:24 INFO - 1499986224744 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ce75b55f-a254-446a-913c-29ddeae06480","name":"selected"}]
15:50:24 INFO - 1499986224748 Marionette TRACE 370 <- [1,5,null,{"value":true}]
15:50:24 INFO - 1499986224750 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}]
15:50:24 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499986224752 Marionette TRACE 370 <- [1,6,null,{"value":null}]
15:50:24 INFO - 1499986224753 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
15:50:24 INFO - 1499986224754 Marionette TRACE 370 <- [1,7,null,{}]
15:50:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 325ms
15:50:24 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:50:24 INFO - 1499986224756 Marionette DEBUG Closed connection 370
15:50:24 INFO - 1499986224757 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50285
15:50:24 INFO - 1499986224757 Marionette DEBUG Closed connection 371
15:50:24 INFO - 1499986224758 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50286
15:50:24 INFO - 1499986224759 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:24 INFO - 1499986224760 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224762 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224764 Marionette TRACE 372 <- [1,1,null,{"sessionId":"cbedb25a-bc36-4ad0-89db-a7d17b7fba16","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224766 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
15:50:24 INFO - 1499986224767 Marionette TRACE 372 <- [1,2,null,{}]
15:50:24 INFO - 1499986224767 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:50:24 INFO - 1499986224768 Marionette TRACE 372 <- [1,3,null,{}]
15:50:24 INFO - 1499986224768 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
15:50:24 INFO - 1499986224769 Marionette TRACE 372 <- [1,4,null,{}]
15:50:24 INFO - 1499986224771 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}]
15:50:24 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499986224773 Marionette TRACE 372 <- [1,5,null,{"value":null}]
15:50:24 INFO - 1499986224774 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
15:50:24 INFO - 1499986224775 Marionette TRACE 372 <- [1,6,null,{}]
15:50:24 INFO - 1499986224778 Marionette DEBUG Closed connection 372
15:50:24 INFO - 1499986224778 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50287
15:50:24 INFO - 1499986224779 Marionette DEBUG Closed connection 373
15:50:24 INFO - 1499986224780 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50288
15:50:24 INFO - 1499986224781 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:24 INFO - 1499986224781 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224790 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224793 Marionette TRACE 374 <- [1,1,null,{"sessionId":"50701cf5-d4b0-4122-a391-d107131d840c","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224795 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
15:50:24 INFO - 1499986224796 Marionette TRACE 374 <- [1,2,null,{}]
15:50:24 INFO - 1499986224798 Marionette DEBUG Closed connection 374
15:50:24 INFO - 1499986224798 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50289
15:50:24 INFO - 1499986224799 Marionette DEBUG Closed connection 375
15:50:24 INFO - 1499986224800 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50290
15:50:24 INFO - 1499986224801 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:50:24 INFO - 1499986224801 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:24 INFO - 1499986224803 Marionette DEBUG Register listener.js for window 15
15:50:24 INFO - 1499986224805 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8c8bec44-71de-45e3-b3cd-3d1837a26e67","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:24 INFO - 1499986224807 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"}]
15:50:24 INFO - 1499986224809 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"
15:50:24 INFO - 1499986224812 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"
15:50:24 INFO - 1499986224812 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"
15:50:24 INFO - 1499986224841 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"
15:50:24 INFO - 1499986224845 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"
15:50:24 INFO - 1499986224856 Marionette TRACE 376 <- [1,2,null,{}]
15:50:24 INFO - 1499986224858 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:50:24 INFO - 1499986224861 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ed66710c-439c-45b7-99bb-833c8f95ed52","ELEMENT":"ed66710c-439c-45b7-99bb-833c8f95ed52"}}]
15:50:24 INFO - 1499986224862 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"ed66710c-439c-45b7-99bb-833c8f95ed52"}]
15:50:25 INFO - 1499986225095 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:50:25 INFO - 1499986225095 Marionette TRACE 376 <- [1,4,null,{}]
15:50:25 INFO - 1499986225097 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}]
15:50:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499986225100 Marionette TRACE 376 <- [1,5,null,{"value":null}]
15:50:25 INFO - 1499986225101 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
15:50:25 INFO - 1499986225102 Marionette TRACE 376 <- [1,6,null,{}]
15:50:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 348ms
15:50:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:50:25 INFO - 1499986225104 Marionette DEBUG Closed connection 376
15:50:25 INFO - 1499986225105 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50291
15:50:25 INFO - 1499986225105 Marionette DEBUG Closed connection 377
15:50:25 INFO - 1499986225106 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50292
15:50:25 INFO - 1499986225107 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:25 INFO - 1499986225108 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225110 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225112 Marionette TRACE 378 <- [1,1,null,{"sessionId":"02e222b7-585b-4c04-996d-6628a5bb4980","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225114 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
15:50:25 INFO - 1499986225115 Marionette TRACE 378 <- [1,2,null,{}]
15:50:25 INFO - 1499986225116 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:50:25 INFO - 1499986225116 Marionette TRACE 378 <- [1,3,null,{}]
15:50:25 INFO - 1499986225117 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
15:50:25 INFO - 1499986225117 Marionette TRACE 378 <- [1,4,null,{}]
15:50:25 INFO - 1499986225119 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}]
15:50:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499986225121 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:50:25 INFO - 1499986225122 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:50:25 INFO - 1499986225123 Marionette TRACE 378 <- [1,6,null,{}]
15:50:25 INFO - 1499986225125 Marionette DEBUG Closed connection 378
15:50:25 INFO - 1499986225126 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50293
15:50:25 INFO - 1499986225127 Marionette DEBUG Closed connection 379
15:50:25 INFO - 1499986225127 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50294
15:50:25 INFO - 1499986225128 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:25 INFO - 1499986225129 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225131 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225134 Marionette TRACE 380 <- [1,1,null,{"sessionId":"4ddd3552-6be0-467e-bdff-427f04180d57","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225135 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
15:50:25 INFO - 1499986225137 Marionette TRACE 380 <- [1,2,null,{}]
15:50:25 INFO - 1499986225138 Marionette DEBUG Closed connection 380
15:50:25 INFO - 1499986225139 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50295
15:50:25 INFO - 1499986225139 Marionette DEBUG Closed connection 381
15:50:25 INFO - 1499986225140 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50296
15:50:25 INFO - 1499986225141 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:50:25 INFO - 1499986225142 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225144 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225146 Marionette TRACE 382 <- [1,1,null,{"sessionId":"9db1c4d9-cbf1-4c72-b79d-450c4b73ea30","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225148 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"}]
15:50:25 INFO - 1499986225150 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"
15:50:25 INFO - 1499986225152 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"
15:50:25 INFO - 1499986225152 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"
15:50:25 INFO - 1499986225168 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"
15:50:25 INFO - 1499986225171 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"
15:50:25 INFO - 1499986225174 Marionette TRACE 382 <- [1,2,null,{}]
15:50:25 INFO - 1499986225182 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:50:25 INFO - 1499986225185 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e3f9ca29-0300-492a-8e75-ba3e9467f515","ELEMENT":"e3f9ca29-0300-492a-8e75-ba3e9467f515"}}]
15:50:25 INFO - 1499986225186 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e3f9ca29-0300-492a-8e75-ba3e9467f515"}]
15:50:25 INFO - 1499986225424 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:50:25 INFO - 1499986225425 Marionette TRACE 382 <- [1,4,null,{}]
15:50:25 INFO - 1499986225426 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}]
15:50:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499986225429 Marionette TRACE 382 <- [1,5,null,{"value":null}]
15:50:25 INFO - 1499986225430 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
15:50:25 INFO - 1499986225431 Marionette TRACE 382 <- [1,6,null,{}]
15:50:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 329ms
15:50:25 INFO - TEST-START | test_click.py TestClick.test_input_file
15:50:25 INFO - 1499986225433 Marionette DEBUG Closed connection 382
15:50:25 INFO - 1499986225434 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50297
15:50:25 INFO - 1499986225434 Marionette DEBUG Closed connection 383
15:50:25 INFO - 1499986225435 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50298
15:50:25 INFO - 1499986225436 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:25 INFO - 1499986225437 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225439 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225441 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5ddb48d8-375f-4337-88c0-8655702a77d0","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225443 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
15:50:25 INFO - 1499986225444 Marionette TRACE 384 <- [1,2,null,{}]
15:50:25 INFO - 1499986225445 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:50:25 INFO - 1499986225445 Marionette TRACE 384 <- [1,3,null,{}]
15:50:25 INFO - 1499986225446 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
15:50:25 INFO - 1499986225446 Marionette TRACE 384 <- [1,4,null,{}]
15:50:25 INFO - 1499986225448 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}]
15:50:25 INFO - TEST-START: test_click.py TestClick.test_input_file1499986225450 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:50:25 INFO - 1499986225451 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:50:25 INFO - 1499986225452 Marionette TRACE 384 <- [1,6,null,{}]
15:50:25 INFO - 1499986225454 Marionette DEBUG Closed connection 384
15:50:25 INFO - 1499986225454 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50299
15:50:25 INFO - 1499986225455 Marionette DEBUG Closed connection 385
15:50:25 INFO - 1499986225456 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50300
15:50:25 INFO - 1499986225457 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:25 INFO - 1499986225457 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225459 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225468 Marionette TRACE 386 <- [1,1,null,{"sessionId":"2a234f08-72ec-4823-b0ad-65ef3c5b98b9","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225470 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
15:50:25 INFO - 1499986225472 Marionette TRACE 386 <- [1,2,null,{}]
15:50:25 INFO - 1499986225473 Marionette DEBUG Closed connection 386
15:50:25 INFO - 1499986225474 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50301
15:50:25 INFO - 1499986225475 Marionette DEBUG Closed connection 387
15:50:25 INFO - 1499986225475 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50302
15:50:25 INFO - 1499986225476 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:50:25 INFO - 1499986225477 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225479 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225481 Marionette TRACE 388 <- [1,1,null,{"sessionId":"70445ca0-19b3-4b86-b117-7e8e8fca1904","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225483 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:50:25 INFO - 1499986225485 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"
15:50:25 INFO - 1499986225487 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"
15:50:25 INFO - 1499986225488 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"
15:50:25 INFO - 1499986225503 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:50:25 INFO - 1499986225506 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:50:25 INFO - 1499986225509 Marionette TRACE 388 <- [1,2,null,{}]
15:50:25 INFO - 1499986225516 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:50:25 INFO - 1499986225518 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c0083d1-9166-4fa7-85a1-c5cd60ea0c20","ELEMENT":"3c0083d1-9166-4fa7-85a1-c5cd60ea0c20"}}]
15:50:25 INFO - 1499986225519 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3c0083d1-9166-4fa7-85a1-c5cd60ea0c20"}]
15:50:25 INFO - 1499986225542 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]
15:50:25 INFO - 1499986225544 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}]
15:50:25 INFO - TEST-END: test_click.py TestClick.test_input_file1499986225546 Marionette TRACE 388 <- [1,5,null,{"value":null}]
15:50:25 INFO - 1499986225547 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
15:50:25 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 117ms
15:50:25 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:50:25 INFO - 1499986225548 Marionette TRACE 388 <- [1,6,null,{}]
15:50:25 INFO - 1499986225550 Marionette DEBUG Closed connection 388
15:50:25 INFO - 1499986225551 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50303
15:50:25 INFO - 1499986225552 Marionette DEBUG Closed connection 389
15:50:25 INFO - 1499986225553 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50304
15:50:25 INFO - 1499986225554 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:25 INFO - 1499986225554 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225556 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225559 Marionette TRACE 390 <- [1,1,null,{"sessionId":"05638602-583c-4c8f-9f11-0c8ff0ac9091","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225560 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
15:50:25 INFO - 1499986225561 Marionette TRACE 390 <- [1,2,null,{}]
15:50:25 INFO - 1499986225562 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:50:25 INFO - 1499986225562 Marionette TRACE 390 <- [1,3,null,{}]
15:50:25 INFO - 1499986225563 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
15:50:25 INFO - 1499986225563 Marionette TRACE 390 <- [1,4,null,{}]
15:50:25 INFO - 1499986225565 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}]
15:50:25 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499986225567 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:50:25 INFO - 1499986225568 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:50:25 INFO - 1499986225569 Marionette TRACE 390 <- [1,6,null,{}]
15:50:25 INFO - 1499986225571 Marionette DEBUG Closed connection 390
15:50:25 INFO - 1499986225571 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50305
15:50:25 INFO - 1499986225572 Marionette DEBUG Closed connection 391
15:50:25 INFO - 1499986225573 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50306
15:50:25 INFO - 1499986225574 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:25 INFO - 1499986225574 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225576 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225578 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0dec4127-8f5e-482f-ba82-b336c8e458ed","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225580 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
15:50:25 INFO - 1499986225581 Marionette TRACE 392 <- [1,2,null,{}]
15:50:25 INFO - 1499986225583 Marionette DEBUG Closed connection 392
15:50:25 INFO - 1499986225583 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50307
15:50:25 INFO - 1499986225584 Marionette DEBUG Closed connection 393
15:50:25 INFO - 1499986225585 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50308
15:50:25 INFO - 1499986225586 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:50:25 INFO - 1499986225586 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225588 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225591 Marionette TRACE 394 <- [1,1,null,{"sessionId":"0d142c89-2089-4f07-b7e6-5500c1a64c9c","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225592 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"}]
15:50:25 INFO - 1499986225594 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:50:25 INFO - 1499986225595 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:50:25 INFO - 1499986225596 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:50:25 INFO - 1499986225606 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"
15:50:25 INFO - 1499986225609 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"
15:50:25 INFO - 1499986225619 Marionette TRACE 394 <- [1,2,null,{}]
15:50:25 INFO - 1499986225620 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:50:25 INFO - 1499986225622 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7739c668-37bf-45ef-a36e-4d8774bcda2a","ELEMENT":"7739c668-37bf-45ef-a36e-4d8774bcda2a"}}]
15:50:25 INFO - 1499986225623 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:50:25 INFO - 1499986225625 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a41b0168-5981-4f85-ab18-1447d4ce0e5b","ELEMENT":"a41b0168-5981-4f85-ab18-1447d4ce0e5b"}}]
15:50:25 INFO - 1499986225626 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7739c668-37bf-45ef-a36e-4d8774bcda2a"}]
15:50:25 INFO - 1499986225859 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:50:25 INFO - 1499986225859 Marionette TRACE 394 <- [1,5,null,{}]
15:50:25 INFO - 1499986225860 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a41b0168-5981-4f85-ab18-1447d4ce0e5b"}]
15:50:25 INFO - 1499986225874 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]
15:50:25 INFO - 1499986225875 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:50:25 INFO - 1499986225878 Marionette TRACE 394 <- [1,7,null,{"value":false}]
15:50:25 INFO - 1499986225879 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}]
15:50:25 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499986225881 Marionette TRACE 394 <- [1,8,null,{"value":null}]
15:50:25 INFO - 1499986225882 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
15:50:25 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 334ms
15:50:25 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:50:25 INFO - 1499986225884 Marionette TRACE 394 <- [1,9,null,{}]
15:50:25 INFO - 1499986225885 Marionette DEBUG Closed connection 394
15:50:25 INFO - 1499986225886 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50309
15:50:25 INFO - 1499986225887 Marionette DEBUG Closed connection 395
15:50:25 INFO - 1499986225888 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50310
15:50:25 INFO - 1499986225889 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:25 INFO - 1499986225889 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225891 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225894 Marionette TRACE 396 <- [1,1,null,{"sessionId":"95401d88-791c-4fde-930d-1f0d37ac1b83","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225896 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
15:50:25 INFO - 1499986225896 Marionette TRACE 396 <- [1,2,null,{}]
15:50:25 INFO - 1499986225897 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:50:25 INFO - 1499986225897 Marionette TRACE 396 <- [1,3,null,{}]
15:50:25 INFO - 1499986225898 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
15:50:25 INFO - 1499986225898 Marionette TRACE 396 <- [1,4,null,{}]
15:50:25 INFO - 1499986225900 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}]
15:50:25 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499986225902 Marionette TRACE 396 <- [1,5,null,{"value":null}]
15:50:25 INFO - 1499986225903 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
15:50:25 INFO - 1499986225904 Marionette TRACE 396 <- [1,6,null,{}]
15:50:25 INFO - 1499986225906 Marionette DEBUG Closed connection 396
15:50:25 INFO - 1499986225907 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50311
15:50:25 INFO - 1499986225907 Marionette DEBUG Closed connection 397
15:50:25 INFO - 1499986225908 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50312
15:50:25 INFO - 1499986225909 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:50:25 INFO - 1499986225910 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225911 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225914 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a475a397-a91c-491f-9742-792fd067129d","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225916 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
15:50:25 INFO - 1499986225917 Marionette TRACE 398 <- [1,2,null,{}]
15:50:25 INFO - 1499986225918 Marionette DEBUG Closed connection 398
15:50:25 INFO - 1499986225919 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50313
15:50:25 INFO - 1499986225920 Marionette DEBUG Closed connection 399
15:50:25 INFO - 1499986225920 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50314
15:50:25 INFO - 1499986225921 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:50:25 INFO - 1499986225922 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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}
15:50:25 INFO - 1499986225924 Marionette DEBUG Register listener.js for window 15
15:50:25 INFO - 1499986225934 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e9458bf7-57bc-4ab8-9c50-839056780157","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":3168,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpuv1req.mozrunner","moz:accessibilityChecks":false}}]
15:50:25 INFO - 1499986225936 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"}]
15:50:25 INFO - 1499986225937 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"
15:50:25 INFO - 1499986225939 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"
15:50:25 INFO - 1499986225939 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"
15:50:25 INFO - 1499986225955 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"
15:50:25 INFO - 1499986225957 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"
15:50:25 INFO - 1499986225960 Marionette TRACE 400 <- [1,2,null,{}]
15:50:25 INFO - 1499986225967 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:50:25 INFO - 1499986225969 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a7018448-2522-4e67-b72b-bfebcad0574c","ELEMENT":"a7018448-2522-4e67-b72b-bfebcad0574c"}}]
15:50:25 INFO - 1499986225970 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a7018448-2522-4e67-b72b-bfebcad0574c"}]
15:50:25 INFO - 1499986225990 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
15:50:25 INFO - 1499986225991 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a7018448-2522-4e67-b72b-bfebcad0574c"}]
15:50:26 INFO - 1499986226005 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element