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]
03:56:34 INFO - 1499338594642 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}]
03:56:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499338594647 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:56:34 INFO - 1499338594648 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:56:34 INFO - 1499338594650 Marionette TRACE 352 <- [1,6,null,{}]
03:56:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 158ms
03:56:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:56:34 INFO - 1499338594652 Marionette DEBUG Closed connection 352
03:56:34 INFO - 1499338594653 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49976
03:56:34 INFO - 1499338594655 Marionette DEBUG Closed connection 353
03:56:34 INFO - 1499338594655 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49977
03:56:34 INFO - 1499338594657 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:34 INFO - 1499338594657 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:34 INFO - 1499338594660 Marionette DEBUG Register listener.js for window 12
03:56:34 INFO - 1499338594664 Marionette TRACE 354 <- [1,1,null,{"sessionId":"25bc8d13-535f-489f-8dbe-49dde4dcc619","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:34 INFO - 1499338594666 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:56:34 INFO - 1499338594667 Marionette TRACE 354 <- [1,2,null,{}]
03:56:34 INFO - 1499338594668 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:56:34 INFO - 1499338594668 Marionette TRACE 354 <- [1,3,null,{}]
03:56:34 INFO - 1499338594669 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:56:34 INFO - 1499338594670 Marionette TRACE 354 <- [1,4,null,{}]
03:56:34 INFO - 1499338594672 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}]
03:56:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499338594675 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:56:34 INFO - 1499338594676 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:56:34 INFO - 1499338594677 Marionette TRACE 354 <- [1,6,null,{}]
03:56:34 INFO - 1499338594679 Marionette DEBUG Closed connection 354
03:56:34 INFO - 1499338594680 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49978
03:56:34 INFO - 1499338594681 Marionette DEBUG Closed connection 355
03:56:34 INFO - 1499338594682 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49979
03:56:34 INFO - 1499338594683 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:34 INFO - 1499338594684 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:34 INFO - 1499338594686 Marionette DEBUG Register listener.js for window 12
03:56:34 INFO - 1499338594690 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d317e4be-acbd-4b26-927e-f5f6f49ef958","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:34 INFO - 1499338594691 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:56:34 INFO - 1499338594693 Marionette TRACE 356 <- [1,2,null,{}]
03:56:34 INFO - 1499338594695 Marionette DEBUG Closed connection 356
03:56:34 INFO - 1499338594696 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49980
03:56:34 INFO - 1499338594697 Marionette DEBUG Closed connection 357
03:56:34 INFO - 1499338594697 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49981
03:56:34 INFO - 1499338594698 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:56:34 INFO - 1499338594699 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:34 INFO - 1499338594702 Marionette DEBUG Register listener.js for window 12
03:56:34 INFO - 1499338594705 Marionette TRACE 358 <- [1,1,null,{"sessionId":"db669f10-b152-4fd9-ae3a-c911273b5d12","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:34 INFO - 1499338594706 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49542/clicks.html"}]
03:56:34 INFO - 1499338594708 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49542/hidden.html"
03:56:34 INFO - 1499338594714 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49542/hidden.html"
03:56:34 INFO - 1499338594715 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49542/hidden.html"
03:56:34 INFO - 1499338594735 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49542/clicks.html"
03:56:34 INFO - 1499338594744 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49542/clicks.html"
03:56:34 INFO - 1499338594748 Marionette TRACE 358 <- [1,2,null,{}]
03:56:34 INFO - 1499338594749 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:56:34 INFO - 1499338594752 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"622968df-3d89-4dad-91a6-963895635c30","ELEMENT":"622968df-3d89-4dad-91a6-963895635c30"}}]
03:56:34 INFO - 1499338594760 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"622968df-3d89-4dad-91a6-963895635c30"}]
03:56:34 INFO - 1499338594814 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49542/clicks.html"
03:56:34 INFO - 1499338594828 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49542/clicks.html"
03:56:34 INFO - 1499338594829 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49542/clicks.html"
03:56:34 INFO - 1499338594864 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49542/test.html"
03:56:34 INFO - 1499338594868 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49542/test.html"
03:56:34 INFO - 1499338594872 Marionette TRACE 358 <- [1,4,null,{}]
03:56:34 INFO - 1499338594884 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:56:34 INFO - 1499338594887 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9900ab20-98cf-4744-8e44-87b82527f67d","ELEMENT":"9900ab20-98cf-4744-8e44-87b82527f67d"}}]
03:56:34 INFO - 1499338594889 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:56:34 INFO - 1499338594890 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:56:34 INFO - 1499338594892 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}]
03:56:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499338594895 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:56:34 INFO - 1499338594896 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:56:34 INFO - 1499338594897 Marionette TRACE 358 <- [1,8,null,{}]
03:56:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 248ms
03:56:34 INFO - TEST-START | test_click.py TestClick.test_container_element
03:56:34 INFO - 1499338594900 Marionette DEBUG Closed connection 358
03:56:34 INFO - 1499338594901 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49984
03:56:34 INFO - 1499338594901 Marionette DEBUG Closed connection 359
03:56:34 INFO - 1499338594902 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49985
03:56:34 INFO - 1499338594903 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:34 INFO - 1499338594904 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:34 INFO - 1499338594907 Marionette DEBUG Register listener.js for window 12
03:56:34 INFO - 1499338594911 Marionette TRACE 360 <- [1,1,null,{"sessionId":"600e69d7-ae8f-445d-97ba-196852059f43","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:34 INFO - 1499338594913 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:56:34 INFO - 1499338594914 Marionette TRACE 360 <- [1,2,null,{}]
03:56:34 INFO - 1499338594915 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:56:34 INFO - 1499338594915 Marionette TRACE 360 <- [1,3,null,{}]
03:56:34 INFO - 1499338594916 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:56:34 INFO - 1499338594917 Marionette TRACE 360 <- [1,4,null,{}]
03:56:34 INFO - 1499338594918 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}]
03:56:34 INFO - TEST-START: test_click.py TestClick.test_container_element1499338594922 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:56:34 INFO - 1499338594922 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:56:34 INFO - 1499338594924 Marionette TRACE 360 <- [1,6,null,{}]
03:56:34 INFO - 1499338594926 Marionette DEBUG Closed connection 360
03:56:34 INFO - 1499338594927 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49986
03:56:34 INFO - 1499338594928 Marionette DEBUG Closed connection 361
03:56:34 INFO - 1499338594929 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49987
03:56:34 INFO - 1499338594930 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:34 INFO - 1499338594930 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:34 INFO - 1499338594933 Marionette DEBUG Register listener.js for window 12
03:56:34 INFO - 1499338594937 Marionette TRACE 362 <- [1,1,null,{"sessionId":"2c9869ee-8d22-4d19-a312-8552ab6f6a73","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:34 INFO - 1499338594938 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:56:34 INFO - 1499338594939 Marionette TRACE 362 <- [1,2,null,{}]
03:56:34 INFO - 1499338594941 Marionette DEBUG Closed connection 362
03:56:34 INFO - 1499338594942 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49988
03:56:34 INFO - 1499338594943 Marionette DEBUG Closed connection 363
03:56:34 INFO - 1499338594944 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49989
03:56:34 INFO - 1499338594945 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:56:34 INFO - 1499338594946 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:34 INFO - 1499338594948 Marionette DEBUG Register listener.js for window 12
03:56:34 INFO - 1499338594952 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f34b9134-6344-493a-b043-130e6628bc0c","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:34 INFO - 1499338594954 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"}]
03:56:34 INFO - 1499338594956 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49542/test.html"
03:56:34 INFO - 1499338594958 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49542/test.html"
03:56:34 INFO - 1499338594959 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49542/test.html"
03:56:34 INFO - 1499338594984 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"
03:56:34 INFO - 1499338594993 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"
03:56:35 INFO - 1499338594997 Marionette TRACE 364 <- [1,2,null,{}]
03:56:35 INFO - 1499338594998 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:56:35 INFO - 1499338595001 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f55f9b4-b744-430c-aac9-d8397ab88efe","ELEMENT":"3f55f9b4-b744-430c-aac9-d8397ab88efe"}}]
03:56:35 INFO - 1499338595008 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"3f55f9b4-b744-430c-aac9-d8397ab88efe"}]
03:56:35 INFO - 1499338595253 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:56:35 INFO - 1499338595253 Marionette TRACE 364 <- [1,4,null,{}]
03:56:35 INFO - 1499338595255 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"3f55f9b4-b744-430c-aac9-d8397ab88efe","name":"selected"}]
03:56:35 INFO - 1499338595260 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:56:35 INFO - 1499338595262 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}]
03:56:35 INFO - TEST-END: test_click.py TestClick.test_container_element1499338595265 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:56:35 INFO - 1499338595266 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:56:35 INFO - 1499338595267 Marionette TRACE 364 <- [1,7,null,{}]
03:56:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
03:56:35 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:56:35 INFO - 1499338595269 Marionette DEBUG Closed connection 364
03:56:35 INFO - 1499338595272 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49990
03:56:35 INFO - 1499338595272 Marionette DEBUG Closed connection 365
03:56:35 INFO - 1499338595273 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49991
03:56:35 INFO - 1499338595274 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:35 INFO - 1499338595275 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:35 INFO - 1499338595278 Marionette DEBUG Register listener.js for window 12
03:56:35 INFO - 1499338595281 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e1c9b560-b20e-4c68-ba11-4cfb983b5072","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:35 INFO - 1499338595283 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:56:35 INFO - 1499338595283 Marionette TRACE 366 <- [1,2,null,{}]
03:56:35 INFO - 1499338595284 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:56:35 INFO - 1499338595285 Marionette TRACE 366 <- [1,3,null,{}]
03:56:35 INFO - 1499338595287 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:56:35 INFO - 1499338595288 Marionette TRACE 366 <- [1,4,null,{}]
03:56:35 INFO - 1499338595290 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}]
03:56:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499338595293 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:56:35 INFO - 1499338595302 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:56:35 INFO - 1499338595303 Marionette TRACE 366 <- [1,6,null,{}]
03:56:35 INFO - 1499338595305 Marionette DEBUG Closed connection 366
03:56:35 INFO - 1499338595306 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49992
03:56:35 INFO - 1499338595307 Marionette DEBUG Closed connection 367
03:56:35 INFO - 1499338595308 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49993
03:56:35 INFO - 1499338595309 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:35 INFO - 1499338595309 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:35 INFO - 1499338595312 Marionette DEBUG Register listener.js for window 12
03:56:35 INFO - 1499338595316 Marionette TRACE 368 <- [1,1,null,{"sessionId":"175130fd-b4ce-438c-b6e5-da859cefc9eb","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:35 INFO - 1499338595317 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:56:35 INFO - 1499338595318 Marionette TRACE 368 <- [1,2,null,{}]
03:56:35 INFO - 1499338595320 Marionette DEBUG Closed connection 368
03:56:35 INFO - 1499338595321 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49994
03:56:35 INFO - 1499338595322 Marionette DEBUG Closed connection 369
03:56:35 INFO - 1499338595323 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49995
03:56:35 INFO - 1499338595324 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:56:35 INFO - 1499338595324 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:35 INFO - 1499338595327 Marionette DEBUG Register listener.js for window 12
03:56:35 INFO - 1499338595331 Marionette TRACE 370 <- [1,1,null,{"sessionId":"dd0da41d-bef4-4c66-b3f4-afbfac50f1d3","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:35 INFO - 1499338595332 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"}]
03:56:35 INFO - 1499338595334 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"
03:56:35 INFO - 1499338595337 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"
03:56:35 INFO - 1499338595338 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"
03:56:35 INFO - 1499338595351 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"
03:56:35 INFO - 1499338595362 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"
03:56:35 INFO - 1499338595365 Marionette TRACE 370 <- [1,2,null,{}]
03:56:35 INFO - 1499338595367 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:56:35 INFO - 1499338595375 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84f329aa-0815-4f62-9e57-8337157c7948","ELEMENT":"84f329aa-0815-4f62-9e57-8337157c7948"}}]
03:56:35 INFO - 1499338595377 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"84f329aa-0815-4f62-9e57-8337157c7948"}]
03:56:35 INFO - 1499338595630 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:56:35 INFO - 1499338595631 Marionette TRACE 370 <- [1,4,null,{}]
03:56:35 INFO - 1499338595632 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"84f329aa-0815-4f62-9e57-8337157c7948","name":"selected"}]
03:56:35 INFO - 1499338595637 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:56:35 INFO - 1499338595639 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}]
03:56:35 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499338595642 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:56:35 INFO - 1499338595643 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:56:35 INFO - 1499338595644 Marionette TRACE 370 <- [1,7,null,{}]
03:56:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 376ms
03:56:35 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:56:35 INFO - 1499338595646 Marionette DEBUG Closed connection 370
03:56:35 INFO - 1499338595647 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49996
03:56:35 INFO - 1499338595648 Marionette DEBUG Closed connection 371
03:56:35 INFO - 1499338595649 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49997
03:56:35 INFO - 1499338595650 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:35 INFO - 1499338595651 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:35 INFO - 1499338595654 Marionette DEBUG Register listener.js for window 12
03:56:35 INFO - 1499338595657 Marionette TRACE 372 <- [1,1,null,{"sessionId":"b5360bf1-6ce3-43e7-8615-838e4cc8ac17","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:35 INFO - 1499338595658 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:56:35 INFO - 1499338595659 Marionette TRACE 372 <- [1,2,null,{}]
03:56:35 INFO - 1499338595660 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:56:35 INFO - 1499338595660 Marionette TRACE 372 <- [1,3,null,{}]
03:56:35 INFO - 1499338595661 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:56:35 INFO - 1499338595662 Marionette TRACE 372 <- [1,4,null,{}]
03:56:35 INFO - 1499338595664 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}]
03:56:35 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499338595667 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:56:35 INFO - 1499338595668 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:56:35 INFO - 1499338595669 Marionette TRACE 372 <- [1,6,null,{}]
03:56:35 INFO - 1499338595671 Marionette DEBUG Closed connection 372
03:56:35 INFO - 1499338595672 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49998
03:56:35 INFO - 1499338595673 Marionette DEBUG Closed connection 373
03:56:35 INFO - 1499338595674 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49999
03:56:35 INFO - 1499338595675 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:35 INFO - 1499338595676 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:35 INFO - 1499338595678 Marionette DEBUG Register listener.js for window 12
03:56:35 INFO - 1499338595682 Marionette TRACE 374 <- [1,1,null,{"sessionId":"282007a1-2f0e-4ebd-94a2-986e3e5ce191","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:35 INFO - 1499338595683 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:56:35 INFO - 1499338595684 Marionette TRACE 374 <- [1,2,null,{}]
03:56:35 INFO - 1499338595686 Marionette DEBUG Closed connection 374
03:56:35 INFO - 1499338595687 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50000
03:56:35 INFO - 1499338595688 Marionette DEBUG Closed connection 375
03:56:35 INFO - 1499338595689 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50001
03:56:35 INFO - 1499338595690 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:56:35 INFO - 1499338595691 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:35 INFO - 1499338595693 Marionette DEBUG Register listener.js for window 12
03:56:35 INFO - 1499338595697 Marionette TRACE 376 <- [1,1,null,{"sessionId":"92e1a033-4b1b-467c-8eff-4f013fbed46b","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:35 INFO - 1499338595698 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"}]
03:56:35 INFO - 1499338595700 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"
03:56:35 INFO - 1499338595703 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"
03:56:35 INFO - 1499338595703 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"
03:56:35 INFO - 1499338595729 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"
03:56:35 INFO - 1499338595733 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"
03:56:35 INFO - 1499338595736 Marionette TRACE 376 <- [1,2,null,{}]
03:56:35 INFO - 1499338595751 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:56:35 INFO - 1499338595755 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7e2cc57d-00f1-430f-8c51-2dbd0c89585e","ELEMENT":"7e2cc57d-00f1-430f-8c51-2dbd0c89585e"}}]
03:56:35 INFO - 1499338595756 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"7e2cc57d-00f1-430f-8c51-2dbd0c89585e"}]
03:56:36 INFO - 1499338595998 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:56:36 INFO - 1499338595999 Marionette TRACE 376 <- [1,4,null,{}]
03:56:36 INFO - 1499338596001 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}]
03:56:36 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499338596004 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:56:36 INFO - 1499338596005 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:56:36 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 362ms
03:56:36 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:56:36 INFO - 1499338596007 Marionette TRACE 376 <- [1,6,null,{}]
03:56:36 INFO - 1499338596009 Marionette DEBUG Closed connection 376
03:56:36 INFO - 1499338596010 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50002
03:56:36 INFO - 1499338596011 Marionette DEBUG Closed connection 377
03:56:36 INFO - 1499338596012 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50003
03:56:36 INFO - 1499338596013 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:36 INFO - 1499338596013 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596016 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596020 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c456bce0-027a-40d8-8604-30576708dcb8","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596021 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:56:36 INFO - 1499338596022 Marionette TRACE 378 <- [1,2,null,{}]
03:56:36 INFO - 1499338596023 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:56:36 INFO - 1499338596024 Marionette TRACE 378 <- [1,3,null,{}]
03:56:36 INFO - 1499338596025 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:56:36 INFO - 1499338596025 Marionette TRACE 378 <- [1,4,null,{}]
03:56:36 INFO - 1499338596027 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}]
03:56:36 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499338596030 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:56:36 INFO - 1499338596031 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:56:36 INFO - 1499338596032 Marionette TRACE 378 <- [1,6,null,{}]
03:56:36 INFO - 1499338596035 Marionette DEBUG Closed connection 378
03:56:36 INFO - 1499338596035 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50004
03:56:36 INFO - 1499338596036 Marionette DEBUG Closed connection 379
03:56:36 INFO - 1499338596037 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50005
03:56:36 INFO - 1499338596038 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:36 INFO - 1499338596039 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596042 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596045 Marionette TRACE 380 <- [1,1,null,{"sessionId":"23658148-73ee-4fc7-8e34-56f7dfcc3e9c","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596047 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:56:36 INFO - 1499338596048 Marionette TRACE 380 <- [1,2,null,{}]
03:56:36 INFO - 1499338596050 Marionette DEBUG Closed connection 380
03:56:36 INFO - 1499338596051 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50006
03:56:36 INFO - 1499338596052 Marionette DEBUG Closed connection 381
03:56:36 INFO - 1499338596053 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50007
03:56:36 INFO - 1499338596054 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:56:36 INFO - 1499338596054 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596057 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596061 Marionette TRACE 382 <- [1,1,null,{"sessionId":"791dcbd1-f10a-41e0-8488-8d806f42b8ea","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596062 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"}]
03:56:36 INFO - 1499338596063 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"
03:56:36 INFO - 1499338596066 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"
03:56:36 INFO - 1499338596066 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"
03:56:36 INFO - 1499338596086 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"
03:56:36 INFO - 1499338596093 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"
03:56:36 INFO - 1499338596096 Marionette TRACE 382 <- [1,2,null,{}]
03:56:36 INFO - 1499338596098 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:56:36 INFO - 1499338596100 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"92017278-ef8b-4865-8657-d802a7011c76","ELEMENT":"92017278-ef8b-4865-8657-d802a7011c76"}}]
03:56:36 INFO - 1499338596101 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"92017278-ef8b-4865-8657-d802a7011c76"}]
03:56:36 INFO - 1499338596362 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:56:36 INFO - 1499338596362 Marionette TRACE 382 <- [1,4,null,{}]
03:56:36 INFO - 1499338596364 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}]
03:56:36 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499338596367 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:56:36 INFO - 1499338596368 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:56:36 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 363ms
03:56:36 INFO - 1499338596370 Marionette TRACE 382 <- [1,6,null,{}]
03:56:36 INFO - TEST-START | test_click.py TestClick.test_input_file
03:56:36 INFO - 1499338596372 Marionette DEBUG Closed connection 382
03:56:36 INFO - 1499338596373 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50008
03:56:36 INFO - 1499338596374 Marionette DEBUG Closed connection 383
03:56:36 INFO - 1499338596375 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50009
03:56:36 INFO - 1499338596377 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:36 INFO - 1499338596377 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596380 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596384 Marionette TRACE 384 <- [1,1,null,{"sessionId":"7bf8028b-4d6b-4590-bfcc-c3fe01727371","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596385 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:56:36 INFO - 1499338596385 Marionette TRACE 384 <- [1,2,null,{}]
03:56:36 INFO - 1499338596386 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:56:36 INFO - 1499338596387 Marionette TRACE 384 <- [1,3,null,{}]
03:56:36 INFO - 1499338596388 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:56:36 INFO - 1499338596388 Marionette TRACE 384 <- [1,4,null,{}]
03:56:36 INFO - 1499338596390 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}]
03:56:36 INFO - TEST-START: test_click.py TestClick.test_input_file1499338596393 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:56:36 INFO - 1499338596394 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:56:36 INFO - 1499338596395 Marionette TRACE 384 <- [1,6,null,{}]
03:56:36 INFO - 1499338596397 Marionette DEBUG Closed connection 384
03:56:36 INFO - 1499338596398 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50010
03:56:36 INFO - 1499338596399 Marionette DEBUG Closed connection 385
03:56:36 INFO - 1499338596400 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50011
03:56:36 INFO - 1499338596401 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:36 INFO - 1499338596402 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596404 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596409 Marionette TRACE 386 <- [1,1,null,{"sessionId":"a098cd8d-d081-419f-8742-1eb156eb81f6","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596410 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:56:36 INFO - 1499338596412 Marionette TRACE 386 <- [1,2,null,{}]
03:56:36 INFO - 1499338596414 Marionette DEBUG Closed connection 386
03:56:36 INFO - 1499338596415 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50012
03:56:36 INFO - 1499338596415 Marionette DEBUG Closed connection 387
03:56:36 INFO - 1499338596416 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50013
03:56:36 INFO - 1499338596417 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:56:36 INFO - 1499338596418 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596421 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596424 Marionette TRACE 388 <- [1,1,null,{"sessionId":"48541c09-66c7-4225-b7a0-203014549d75","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596425 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:56:36 INFO - 1499338596427 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"
03:56:36 INFO - 1499338596430 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"
03:56:36 INFO - 1499338596430 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"
03:56:36 INFO - 1499338596450 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:56:36 INFO - 1499338596455 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:56:36 INFO - 1499338596466 Marionette TRACE 388 <- [1,2,null,{}]
03:56:36 INFO - 1499338596468 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:56:36 INFO - 1499338596471 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"abcdfc61-41e0-44b3-ad50-b7242faf5c13","ELEMENT":"abcdfc61-41e0-44b3-ad50-b7242faf5c13"}}]
03:56:36 INFO - 1499338596473 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"abcdfc61-41e0-44b3-ad50-b7242faf5c13"}]
03:56:36 INFO - 1499338596501 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]
03:56:36 INFO - 1499338596503 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}]
03:56:36 INFO - TEST-END: test_click.py TestClick.test_input_file1499338596506 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:56:36 INFO - 1499338596507 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:56:36 INFO - 1499338596509 Marionette TRACE 388 <- [1,6,null,{}]
03:56:36 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 138ms
03:56:36 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:56:36 INFO - 1499338596511 Marionette DEBUG Closed connection 388
03:56:36 INFO - 1499338596512 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50014
03:56:36 INFO - 1499338596513 Marionette DEBUG Closed connection 389
03:56:36 INFO - 1499338596514 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50015
03:56:36 INFO - 1499338596515 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:36 INFO - 1499338596516 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596519 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596522 Marionette TRACE 390 <- [1,1,null,{"sessionId":"cf4edcf7-2cde-4379-8405-5b230c3fc660","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596524 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:56:36 INFO - 1499338596524 Marionette TRACE 390 <- [1,2,null,{}]
03:56:36 INFO - 1499338596525 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:56:36 INFO - 1499338596526 Marionette TRACE 390 <- [1,3,null,{}]
03:56:36 INFO - 1499338596527 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:56:36 INFO - 1499338596527 Marionette TRACE 390 <- [1,4,null,{}]
03:56:36 INFO - 1499338596529 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}]
03:56:36 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499338596532 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:56:36 INFO - 1499338596533 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:56:36 INFO - 1499338596535 Marionette TRACE 390 <- [1,6,null,{}]
03:56:36 INFO - 1499338596537 Marionette DEBUG Closed connection 390
03:56:36 INFO - 1499338596538 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50016
03:56:36 INFO - 1499338596539 Marionette DEBUG Closed connection 391
03:56:36 INFO - 1499338596540 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50017
03:56:36 INFO - 1499338596541 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:36 INFO - 1499338596542 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596544 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596548 Marionette TRACE 392 <- [1,1,null,{"sessionId":"31ad5d84-5c6c-4eff-b744-ad848ff4d4f3","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596549 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:56:36 INFO - 1499338596551 Marionette TRACE 392 <- [1,2,null,{}]
03:56:36 INFO - 1499338596553 Marionette DEBUG Closed connection 392
03:56:36 INFO - 1499338596554 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50018
03:56:36 INFO - 1499338596555 Marionette DEBUG Closed connection 393
03:56:36 INFO - 1499338596556 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50019
03:56:36 INFO - 1499338596557 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:56:36 INFO - 1499338596557 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596560 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596563 Marionette TRACE 394 <- [1,1,null,{"sessionId":"5e1f5c29-2e6e-424e-a321-3788c332e742","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596565 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"}]
03:56:36 INFO - 1499338596566 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:56:36 INFO - 1499338596569 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:56:36 INFO - 1499338596569 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:56:36 INFO - 1499338596593 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"
03:56:36 INFO - 1499338596596 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"
03:56:36 INFO - 1499338596599 Marionette TRACE 394 <- [1,2,null,{}]
03:56:36 INFO - 1499338596600 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:56:36 INFO - 1499338596610 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"06373aba-e4ac-4355-929c-ede005fd17c8","ELEMENT":"06373aba-e4ac-4355-929c-ede005fd17c8"}}]
03:56:36 INFO - 1499338596611 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:56:36 INFO - 1499338596614 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa9d1e26-b341-4e53-8f25-abe813063a28","ELEMENT":"aa9d1e26-b341-4e53-8f25-abe813063a28"}}]
03:56:36 INFO - 1499338596615 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"06373aba-e4ac-4355-929c-ede005fd17c8"}]
03:56:36 INFO - 1499338596856 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:56:36 INFO - 1499338596857 Marionette TRACE 394 <- [1,5,null,{}]
03:56:36 INFO - 1499338596858 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"aa9d1e26-b341-4e53-8f25-abe813063a28"}]
03:56:36 INFO - 1499338596876 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]
03:56:36 INFO - 1499338596878 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:56:36 INFO - 1499338596881 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:56:36 INFO - 1499338596882 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}]
03:56:36 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499338596885 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:56:36 INFO - 1499338596886 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:56:36 INFO - 1499338596888 Marionette TRACE 394 <- [1,9,null,{}]
03:56:36 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 378ms
03:56:36 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:56:36 INFO - 1499338596890 Marionette DEBUG Closed connection 394
03:56:36 INFO - 1499338596891 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50020
03:56:36 INFO - 1499338596892 Marionette DEBUG Closed connection 395
03:56:36 INFO - 1499338596893 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50021
03:56:36 INFO - 1499338596894 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:36 INFO - 1499338596901 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596904 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596908 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6db8e325-4117-40ee-ba30-9406f96533ff","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596909 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:56:36 INFO - 1499338596910 Marionette TRACE 396 <- [1,2,null,{}]
03:56:36 INFO - 1499338596911 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:56:36 INFO - 1499338596912 Marionette TRACE 396 <- [1,3,null,{}]
03:56:36 INFO - 1499338596912 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:56:36 INFO - 1499338596913 Marionette TRACE 396 <- [1,4,null,{}]
03:56:36 INFO - 1499338596915 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}]
03:56:36 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499338596918 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:56:36 INFO - 1499338596919 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:56:36 INFO - 1499338596920 Marionette TRACE 396 <- [1,6,null,{}]
03:56:36 INFO - 1499338596922 Marionette DEBUG Closed connection 396
03:56:36 INFO - 1499338596923 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50022
03:56:36 INFO - 1499338596924 Marionette DEBUG Closed connection 397
03:56:36 INFO - 1499338596925 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50023
03:56:36 INFO - 1499338596926 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:56:36 INFO - 1499338596927 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596929 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596933 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1fc3bbae-927a-44d6-acb3-2df2673e8980","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596934 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:56:36 INFO - 1499338596935 Marionette TRACE 398 <- [1,2,null,{}]
03:56:36 INFO - 1499338596937 Marionette DEBUG Closed connection 398
03:56:36 INFO - 1499338596938 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50024
03:56:36 INFO - 1499338596939 Marionette DEBUG Closed connection 399
03:56:36 INFO - 1499338596940 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50025
03:56:36 INFO - 1499338596942 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:56:36 INFO - 1499338596942 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}
03:56:36 INFO - 1499338596945 Marionette DEBUG Register listener.js for window 12
03:56:36 INFO - 1499338596949 Marionette TRACE 400 <- [1,1,null,{"sessionId":"43ec7e7d-d206-4b73-bdd5-d5f45682ec52","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpohcenq.mozrunner","moz:accessibilityChecks":false}}]
03:56:36 INFO - 1499338596950 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"}]
03:56:36 INFO - 1499338596951 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"
03:56:36 INFO - 1499338596954 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"
03:56:36 INFO - 1499338596954 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"
03:56:36 INFO - 1499338596969 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"
03:56:36 INFO - 1499338596973 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"
03:56:36 INFO - 1499338596984 Marionette TRACE 400 <- [1,2,null,{}]
03:56:36 INFO - 1499338596986 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:56:36 INFO - 1499338596990 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ed6c380e-5785-409f-bafa-8c7976f54469","ELEMENT":"ed6c380e-5785-409f-bafa-8c7976f54469"}}]
03:56:36 INFO - 1499338596991 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"ed6c380e-5785-409f-bafa-8c7976f54469"}]
03:56:37 INFO - 1499338597014 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:56:37 INFO - 1499338597015 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"ed6c380e-5785-409f-bafa-8c7976f54469"}]
03:56:37 INFO - 1499338597034 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element