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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
16:23:51 INFO - 1500938631705 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}]
16:23:51 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500938631709 Marionette TRACE 352 <- [1,5,null,{"value":null}]
16:23:51 INFO - 1500938631710 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
16:23:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 170ms
16:23:51 INFO - 1500938631713 Marionette TRACE 352 <- [1,6,null,{}]
16:23:51 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
16:23:51 INFO - 1500938631715 Marionette DEBUG Closed connection 352
16:23:51 INFO - 1500938631716 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50016
16:23:51 INFO - 1500938631717 Marionette DEBUG Closed connection 353
16:23:51 INFO - 1500938631718 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50017
16:23:51 INFO - 1500938631719 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:51 INFO - 1500938631720 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:51 INFO - 1500938631723 Marionette DEBUG Register listener.js for window 4294967297
16:23:51 INFO - 1500938631728 Marionette TRACE 354 <- [1,1,null,{"sessionId":"972e90a9-0fc4-4759-976d-7e62ccd8ef48","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:51 INFO - 1500938631729 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
16:23:51 INFO - 1500938631729 Marionette TRACE 354 <- [1,2,null,{}]
16:23:51 INFO - 1500938631730 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:23:51 INFO - 1500938631731 Marionette TRACE 354 <- [1,3,null,{}]
16:23:51 INFO - 1500938631732 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
16:23:51 INFO - 1500938631732 Marionette TRACE 354 <- [1,4,null,{}]
16:23:51 INFO - 1500938631734 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}]
16:23:51 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500938631738 Marionette TRACE 354 <- [1,5,null,{"value":null}]
16:23:51 INFO - 1500938631739 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
16:23:51 INFO - 1500938631740 Marionette TRACE 354 <- [1,6,null,{}]
16:23:51 INFO - 1500938631742 Marionette DEBUG Closed connection 354
16:23:51 INFO - 1500938631743 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50018
16:23:51 INFO - 1500938631744 Marionette DEBUG Closed connection 355
16:23:51 INFO - 1500938631745 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50019
16:23:51 INFO - 1500938631746 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:51 INFO - 1500938631747 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:51 INFO - 1500938631750 Marionette DEBUG Register listener.js for window 4294967297
16:23:51 INFO - 1500938631755 Marionette TRACE 356 <- [1,1,null,{"sessionId":"e1327170-b3de-4c96-b529-d8109e12d9cd","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:51 INFO - 1500938631756 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
16:23:51 INFO - 1500938631758 Marionette TRACE 356 <- [1,2,null,{}]
16:23:51 INFO - 1500938631759 Marionette DEBUG Closed connection 356
16:23:51 INFO - 1500938631760 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50020
16:23:51 INFO - 1500938631761 Marionette DEBUG Closed connection 357
16:23:51 INFO - 1500938631762 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50021
16:23:51 INFO - 1500938631763 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:23:51 INFO - 1500938631764 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:51 INFO - 1500938631767 Marionette DEBUG Register listener.js for window 4294967297
16:23:51 INFO - 1500938631771 Marionette TRACE 358 <- [1,1,null,{"sessionId":"64446813-0fc3-4a31-80ce-c9d309ce88c2","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:51 INFO - 1500938631772 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49572/clicks.html"}]
16:23:51 INFO - 1500938631774 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49572/hidden.html"
16:23:51 INFO - 1500938631783 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49572/hidden.html"
16:23:51 INFO - 1500938631783 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49572/hidden.html"
16:23:51 INFO - 1500938631791 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49572/clicks.html"
16:23:51 INFO - 1500938631798 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49572/clicks.html"
16:23:51 INFO - 1500938631803 Marionette TRACE 358 <- [1,2,null,{}]
16:23:51 INFO - 1500938631809 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
16:23:51 INFO - 1500938631814 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a28823f0-b0d8-482e-8d3b-b1ac20548b38","ELEMENT":"a28823f0-b0d8-482e-8d3b-b1ac20548b38"}}]
16:23:51 INFO - 1500938631820 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a28823f0-b0d8-482e-8d3b-b1ac20548b38"}]
16:23:51 INFO - 1500938631863 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49572/clicks.html"
16:23:51 INFO - 1500938631872 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49572/clicks.html"
16:23:51 INFO - 1500938631873 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49572/clicks.html"
16:23:51 INFO - 1500938631881 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49572/test.html"
16:23:51 INFO - 1500938631886 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49572/test.html"
16:23:51 INFO - 1500938631892 Marionette TRACE 358 <- [1,4,null,{}]
16:23:51 INFO - 1500938631897 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
16:23:51 INFO - 1500938631905 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4bcf4e7-62ea-46ce-bc0c-30cfa5ec740f","ELEMENT":"a4bcf4e7-62ea-46ce-bc0c-30cfa5ec740f"}}]
16:23:51 INFO - 1500938631906 Marionette TRACE 358 -> [0,6,"getTitle",{}]
16:23:51 INFO - 1500938631907 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
16:23:51 INFO - 1500938631909 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}]
16:23:51 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500938631912 Marionette TRACE 358 <- [1,7,null,{"value":null}]
16:23:51 INFO - 1500938631914 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
16:23:51 INFO - 1500938631915 Marionette TRACE 358 <- [1,8,null,{}]
16:23:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 201ms
16:23:51 INFO - TEST-START | test_click.py TestClick.test_container_element
16:23:51 INFO - 1500938631917 Marionette DEBUG Closed connection 358
16:23:51 INFO - 1500938631918 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50024
16:23:51 INFO - 1500938631919 Marionette DEBUG Closed connection 359
16:23:51 INFO - 1500938631920 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50025
16:23:51 INFO - 1500938631921 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:51 INFO - 1500938631922 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:51 INFO - 1500938631925 Marionette DEBUG Register listener.js for window 4294967297
16:23:51 INFO - 1500938631929 Marionette TRACE 360 <- [1,1,null,{"sessionId":"08d10d02-810b-4854-9529-f0763c84f552","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:51 INFO - 1500938631930 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
16:23:51 INFO - 1500938631931 Marionette TRACE 360 <- [1,2,null,{}]
16:23:51 INFO - 1500938631932 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:23:51 INFO - 1500938631932 Marionette TRACE 360 <- [1,3,null,{}]
16:23:51 INFO - 1500938631933 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
16:23:51 INFO - 1500938631934 Marionette TRACE 360 <- [1,4,null,{}]
16:23:51 INFO - 1500938631936 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}]
16:23:51 INFO - TEST-START: test_click.py TestClick.test_container_element1500938631939 Marionette TRACE 360 <- [1,5,null,{"value":null}]
16:23:51 INFO - 1500938631940 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
16:23:51 INFO - 1500938631942 Marionette TRACE 360 <- [1,6,null,{}]
16:23:51 INFO - 1500938631943 Marionette DEBUG Closed connection 360
16:23:51 INFO - 1500938631944 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50026
16:23:51 INFO - 1500938631945 Marionette DEBUG Closed connection 361
16:23:51 INFO - 1500938631946 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50027
16:23:51 INFO - 1500938631947 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:51 INFO - 1500938631948 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:51 INFO - 1500938631951 Marionette DEBUG Register listener.js for window 4294967297
16:23:51 INFO - 1500938631954 Marionette TRACE 362 <- [1,1,null,{"sessionId":"26e99fa0-a18f-4a4c-9d3a-daad705ebe5b","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:51 INFO - 1500938631956 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
16:23:51 INFO - 1500938631957 Marionette TRACE 362 <- [1,2,null,{}]
16:23:51 INFO - 1500938631959 Marionette DEBUG Closed connection 362
16:23:51 INFO - 1500938631960 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50028
16:23:51 INFO - 1500938631961 Marionette DEBUG Closed connection 363
16:23:51 INFO - 1500938631961 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50029
16:23:51 INFO - 1500938631962 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:23:51 INFO - 1500938631963 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:51 INFO - 1500938631966 Marionette DEBUG Register listener.js for window 4294967297
16:23:51 INFO - 1500938631970 Marionette TRACE 364 <- [1,1,null,{"sessionId":"add05e35-8121-4325-9a0b-27dc739511ff","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:51 INFO - 1500938631972 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"}]
16:23:51 INFO - 1500938631974 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49572/test.html"
16:23:51 INFO - 1500938631978 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49572/test.html"
16:23:51 INFO - 1500938631978 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49572/test.html"
16:23:51 INFO - 1500938631988 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"
16:23:51 INFO - 1500938631994 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"
16:23:52 INFO - 1500938632001 Marionette TRACE 364 <- [1,2,null,{}]
16:23:52 INFO - 1500938632013 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
16:23:52 INFO - 1500938632017 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6543de9b-7fcf-47bf-937a-8c4cc9e200e8","ELEMENT":"6543de9b-7fcf-47bf-937a-8c4cc9e200e8"}}]
16:23:52 INFO - 1500938632018 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6543de9b-7fcf-47bf-937a-8c4cc9e200e8"}]
16:23:52 INFO - 1500938632270 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:23:52 INFO - 1500938632271 Marionette TRACE 364 <- [1,4,null,{}]
16:23:52 INFO - 1500938632272 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6543de9b-7fcf-47bf-937a-8c4cc9e200e8","name":"selected"}]
16:23:52 INFO - 1500938632278 Marionette TRACE 364 <- [1,5,null,{"value":true}]
16:23:52 INFO - 1500938632280 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}]
16:23:52 INFO - TEST-END: test_click.py TestClick.test_container_element1500938632283 Marionette TRACE 364 <- [1,6,null,{"value":null}]
16:23:52 INFO - 1500938632285 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
16:23:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 370ms
16:23:52 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
16:23:52 INFO - 1500938632286 Marionette TRACE 364 <- [1,7,null,{}]
16:23:52 INFO - 1500938632288 Marionette DEBUG Closed connection 364
16:23:52 INFO - 1500938632289 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50030
16:23:52 INFO - 1500938632290 Marionette DEBUG Closed connection 365
16:23:52 INFO - 1500938632291 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50031
16:23:52 INFO - 1500938632292 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:52 INFO - 1500938632292 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:52 INFO - 1500938632296 Marionette DEBUG Register listener.js for window 4294967297
16:23:52 INFO - 1500938632299 Marionette TRACE 366 <- [1,1,null,{"sessionId":"23528955-6689-4625-9e1e-cb0f663a6ba0","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:52 INFO - 1500938632301 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
16:23:52 INFO - 1500938632301 Marionette TRACE 366 <- [1,2,null,{}]
16:23:52 INFO - 1500938632302 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:23:52 INFO - 1500938632303 Marionette TRACE 366 <- [1,3,null,{}]
16:23:52 INFO - 1500938632304 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
16:23:52 INFO - 1500938632305 Marionette TRACE 366 <- [1,4,null,{}]
16:23:52 INFO - 1500938632307 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}]
16:23:52 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500938632310 Marionette TRACE 366 <- [1,5,null,{"value":null}]
16:23:52 INFO - 1500938632311 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
16:23:52 INFO - 1500938632313 Marionette TRACE 366 <- [1,6,null,{}]
16:23:52 INFO - 1500938632315 Marionette DEBUG Closed connection 366
16:23:52 INFO - 1500938632315 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50032
16:23:52 INFO - 1500938632316 Marionette DEBUG Closed connection 367
16:23:52 INFO - 1500938632317 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50033
16:23:52 INFO - 1500938632318 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:52 INFO - 1500938632319 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:52 INFO - 1500938632322 Marionette DEBUG Register listener.js for window 4294967297
16:23:52 INFO - 1500938632326 Marionette TRACE 368 <- [1,1,null,{"sessionId":"7e27d27d-136e-4cbd-955e-181119ec53ef","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:52 INFO - 1500938632327 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
16:23:52 INFO - 1500938632329 Marionette TRACE 368 <- [1,2,null,{}]
16:23:52 INFO - 1500938632330 Marionette DEBUG Closed connection 368
16:23:52 INFO - 1500938632331 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50034
16:23:52 INFO - 1500938632332 Marionette DEBUG Closed connection 369
16:23:52 INFO - 1500938632333 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50035
16:23:52 INFO - 1500938632334 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:23:52 INFO - 1500938632335 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:52 INFO - 1500938632338 Marionette DEBUG Register listener.js for window 4294967297
16:23:52 INFO - 1500938632342 Marionette TRACE 370 <- [1,1,null,{"sessionId":"83cf8f1f-9797-4f56-8d13-d2859faed0b1","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:52 INFO - 1500938632343 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"}]
16:23:52 INFO - 1500938632345 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"
16:23:52 INFO - 1500938632348 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"
16:23:52 INFO - 1500938632348 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"
16:23:52 INFO - 1500938632355 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"
16:23:52 INFO - 1500938632359 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"
16:23:52 INFO - 1500938632364 Marionette TRACE 370 <- [1,2,null,{}]
16:23:52 INFO - 1500938632378 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
16:23:52 INFO - 1500938632387 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cccf7360-925f-4f23-a083-482276ca8626","ELEMENT":"cccf7360-925f-4f23-a083-482276ca8626"}}]
16:23:52 INFO - 1500938632389 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"cccf7360-925f-4f23-a083-482276ca8626"}]
16:23:52 INFO - 1500938632641 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:23:52 INFO - 1500938632642 Marionette TRACE 370 <- [1,4,null,{}]
16:23:52 INFO - 1500938632643 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"cccf7360-925f-4f23-a083-482276ca8626","name":"selected"}]
16:23:52 INFO - 1500938632648 Marionette TRACE 370 <- [1,5,null,{"value":true}]
16:23:52 INFO - 1500938632651 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}]
16:23:52 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500938632654 Marionette TRACE 370 <- [1,6,null,{"value":null}]
16:23:52 INFO - 1500938632655 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
16:23:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 370ms
16:23:52 INFO - TEST-START | test_click.py TestClick.test_css_transforms
16:23:52 INFO - 1500938632657 Marionette TRACE 370 <- [1,7,null,{}]
16:23:52 INFO - 1500938632659 Marionette DEBUG Closed connection 370
16:23:52 INFO - 1500938632659 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50036
16:23:52 INFO - 1500938632660 Marionette DEBUG Closed connection 371
16:23:52 INFO - 1500938632661 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50037
16:23:52 INFO - 1500938632662 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:52 INFO - 1500938632663 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:52 INFO - 1500938632666 Marionette DEBUG Register listener.js for window 4294967297
16:23:52 INFO - 1500938632679 Marionette TRACE 372 <- [1,1,null,{"sessionId":"efe86782-63b6-4530-bc8b-7f225910d535","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:52 INFO - 1500938632680 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
16:23:52 INFO - 1500938632681 Marionette TRACE 372 <- [1,2,null,{}]
16:23:52 INFO - 1500938632682 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:23:52 INFO - 1500938632682 Marionette TRACE 372 <- [1,3,null,{}]
16:23:52 INFO - 1500938632683 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
16:23:52 INFO - 1500938632684 Marionette TRACE 372 <- [1,4,null,{}]
16:23:52 INFO - 1500938632686 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}]
16:23:52 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500938632689 Marionette TRACE 372 <- [1,5,null,{"value":null}]
16:23:52 INFO - 1500938632690 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
16:23:52 INFO - 1500938632692 Marionette TRACE 372 <- [1,6,null,{}]
16:23:52 INFO - 1500938632693 Marionette DEBUG Closed connection 372
16:23:52 INFO - 1500938632694 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50038
16:23:52 INFO - 1500938632695 Marionette DEBUG Closed connection 373
16:23:52 INFO - 1500938632696 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50039
16:23:52 INFO - 1500938632697 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:52 INFO - 1500938632698 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:52 INFO - 1500938632701 Marionette DEBUG Register listener.js for window 4294967297
16:23:52 INFO - 1500938632704 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e48684ff-428c-49bf-a5e7-d0fe5ff2b908","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:52 INFO - 1500938632706 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
16:23:52 INFO - 1500938632707 Marionette TRACE 374 <- [1,2,null,{}]
16:23:52 INFO - 1500938632709 Marionette DEBUG Closed connection 374
16:23:52 INFO - 1500938632710 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50040
16:23:52 INFO - 1500938632711 Marionette DEBUG Closed connection 375
16:23:52 INFO - 1500938632711 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50041
16:23:52 INFO - 1500938632713 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:23:52 INFO - 1500938632713 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:52 INFO - 1500938632716 Marionette DEBUG Register listener.js for window 4294967297
16:23:52 INFO - 1500938632720 Marionette TRACE 376 <- [1,1,null,{"sessionId":"84915391-e231-4be1-a390-badf2edaf744","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:52 INFO - 1500938632721 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"}]
16:23:52 INFO - 1500938632724 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"
16:23:52 INFO - 1500938632727 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"
16:23:52 INFO - 1500938632727 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"
16:23:52 INFO - 1500938632735 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"
16:23:52 INFO - 1500938632738 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"
16:23:52 INFO - 1500938632746 Marionette TRACE 376 <- [1,2,null,{}]
16:23:52 INFO - 1500938632759 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
16:23:52 INFO - 1500938632762 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"105c7fe1-39d0-4930-9fd9-e2c9218c699c","ELEMENT":"105c7fe1-39d0-4930-9fd9-e2c9218c699c"}}]
16:23:52 INFO - 1500938632764 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"105c7fe1-39d0-4930-9fd9-e2c9218c699c"}]
16:23:53 INFO - 1500938633008 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:23:53 INFO - 1500938633009 Marionette TRACE 376 <- [1,4,null,{}]
16:23:53 INFO - 1500938633011 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}]
16:23:53 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 360ms
16:23:53 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
16:23:53 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500938633015 Marionette TRACE 376 <- [1,5,null,{"value":null}]
16:23:53 INFO - 1500938633016 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
16:23:53 INFO - 1500938633017 Marionette TRACE 376 <- [1,6,null,{}]
16:23:53 INFO - 1500938633019 Marionette DEBUG Closed connection 376
16:23:53 INFO - 1500938633020 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50042
16:23:53 INFO - 1500938633021 Marionette DEBUG Closed connection 377
16:23:53 INFO - 1500938633022 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50043
16:23:53 INFO - 1500938633023 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:53 INFO - 1500938633024 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633027 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633031 Marionette TRACE 378 <- [1,1,null,{"sessionId":"81278cd9-c60d-4d8c-af38-0b50aafa0a03","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633032 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
16:23:53 INFO - 1500938633033 Marionette TRACE 378 <- [1,2,null,{}]
16:23:53 INFO - 1500938633034 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:23:53 INFO - 1500938633034 Marionette TRACE 378 <- [1,3,null,{}]
16:23:53 INFO - 1500938633035 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
16:23:53 INFO - 1500938633036 Marionette TRACE 378 <- [1,4,null,{}]
16:23:53 INFO - 1500938633038 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}]
16:23:53 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500938633041 Marionette TRACE 378 <- [1,5,null,{"value":null}]
16:23:53 INFO - 1500938633042 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
16:23:53 INFO - 1500938633044 Marionette TRACE 378 <- [1,6,null,{}]
16:23:53 INFO - 1500938633046 Marionette DEBUG Closed connection 378
16:23:53 INFO - 1500938633046 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50044
16:23:53 INFO - 1500938633047 Marionette DEBUG Closed connection 379
16:23:53 INFO - 1500938633048 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50045
16:23:53 INFO - 1500938633049 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:53 INFO - 1500938633050 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633053 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633057 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b97ad1fa-aa5c-4a73-b9f2-1c96d4b37295","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633058 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
16:23:53 INFO - 1500938633060 Marionette TRACE 380 <- [1,2,null,{}]
16:23:53 INFO - 1500938633062 Marionette DEBUG Closed connection 380
16:23:53 INFO - 1500938633063 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50046
16:23:53 INFO - 1500938633063 Marionette DEBUG Closed connection 381
16:23:53 INFO - 1500938633066 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50047
16:23:53 INFO - 1500938633067 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:23:53 INFO - 1500938633068 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633071 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633075 Marionette TRACE 382 <- [1,1,null,{"sessionId":"b2f19293-bb8a-44f8-b6c2-36f33292b46a","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633076 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"}]
16:23:53 INFO - 1500938633078 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"
16:23:53 INFO - 1500938633080 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"
16:23:53 INFO - 1500938633081 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"
16:23:53 INFO - 1500938633089 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"
16:23:53 INFO - 1500938633092 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"
16:23:53 INFO - 1500938633098 Marionette TRACE 382 <- [1,2,null,{}]
16:23:53 INFO - 1500938633109 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
16:23:53 INFO - 1500938633114 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"59345978-a17d-4275-b66b-52671669a297","ELEMENT":"59345978-a17d-4275-b66b-52671669a297"}}]
16:23:53 INFO - 1500938633116 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"59345978-a17d-4275-b66b-52671669a297"}]
16:23:53 INFO - 1500938633376 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:23:53 INFO - 1500938633377 Marionette TRACE 382 <- [1,4,null,{}]
16:23:53 INFO - 1500938633379 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}]
16:23:53 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500938633382 Marionette TRACE 382 <- [1,5,null,{"value":null}]
16:23:53 INFO - 1500938633383 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
16:23:53 INFO - 1500938633385 Marionette TRACE 382 <- [1,6,null,{}]
16:23:53 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 367ms
16:23:53 INFO - TEST-START | test_click.py TestClick.test_input_file
16:23:53 INFO - 1500938633387 Marionette DEBUG Closed connection 382
16:23:53 INFO - 1500938633388 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50048
16:23:53 INFO - 1500938633389 Marionette DEBUG Closed connection 383
16:23:53 INFO - 1500938633389 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50049
16:23:53 INFO - 1500938633390 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:53 INFO - 1500938633391 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633394 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633399 Marionette TRACE 384 <- [1,1,null,{"sessionId":"9460c7a9-0cde-429f-b911-3b5f04050367","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633400 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
16:23:53 INFO - 1500938633401 Marionette TRACE 384 <- [1,2,null,{}]
16:23:53 INFO - 1500938633401 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:23:53 INFO - 1500938633402 Marionette TRACE 384 <- [1,3,null,{}]
16:23:53 INFO - 1500938633403 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
16:23:53 INFO - 1500938633404 Marionette TRACE 384 <- [1,4,null,{}]
16:23:53 INFO - 1500938633406 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}]
16:23:53 INFO - TEST-START: test_click.py TestClick.test_input_file1500938633409 Marionette TRACE 384 <- [1,5,null,{"value":null}]
16:23:53 INFO - 1500938633410 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
16:23:53 INFO - 1500938633412 Marionette TRACE 384 <- [1,6,null,{}]
16:23:53 INFO - 1500938633414 Marionette DEBUG Closed connection 384
16:23:53 INFO - 1500938633415 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50050
16:23:53 INFO - 1500938633416 Marionette DEBUG Closed connection 385
16:23:53 INFO - 1500938633416 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50051
16:23:53 INFO - 1500938633417 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:53 INFO - 1500938633418 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633421 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633425 Marionette TRACE 386 <- [1,1,null,{"sessionId":"07cb4716-4668-4955-ae94-cde8848b53a2","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633426 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
16:23:53 INFO - 1500938633428 Marionette TRACE 386 <- [1,2,null,{}]
16:23:53 INFO - 1500938633430 Marionette DEBUG Closed connection 386
16:23:53 INFO - 1500938633431 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50052
16:23:53 INFO - 1500938633432 Marionette DEBUG Closed connection 387
16:23:53 INFO - 1500938633432 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50053
16:23:53 INFO - 1500938633434 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:23:53 INFO - 1500938633434 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633441 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633445 Marionette TRACE 388 <- [1,1,null,{"sessionId":"7db68bab-5f9e-46da-b77a-13e03090360a","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633446 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
16:23:53 INFO - 1500938633448 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"
16:23:53 INFO - 1500938633451 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"
16:23:53 INFO - 1500938633451 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"
16:23:53 INFO - 1500938633459 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:23:53 INFO - 1500938633464 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:23:53 INFO - 1500938633472 Marionette TRACE 388 <- [1,2,null,{}]
16:23:53 INFO - 1500938633477 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
16:23:53 INFO - 1500938633480 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2bc89df7-c550-4ee4-a3eb-256fbdb3a432","ELEMENT":"2bc89df7-c550-4ee4-a3eb-256fbdb3a432"}}]
16:23:53 INFO - 1500938633487 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2bc89df7-c550-4ee4-a3eb-256fbdb3a432"}]
16:23:53 INFO - 1500938633521 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
16:23:53 INFO - 1500938633523 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}]
16:23:53 INFO - TEST-END: test_click.py TestClick.test_input_file1500938633527 Marionette TRACE 388 <- [1,5,null,{"value":null}]
16:23:53 INFO - 1500938633528 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
16:23:53 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 144ms
16:23:53 INFO - TEST-START | test_click.py TestClick.test_obscured_element
16:23:53 INFO - 1500938633529 Marionette TRACE 388 <- [1,6,null,{}]
16:23:53 INFO - 1500938633531 Marionette DEBUG Closed connection 388
16:23:53 INFO - 1500938633532 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50054
16:23:53 INFO - 1500938633533 Marionette DEBUG Closed connection 389
16:23:53 INFO - 1500938633534 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50055
16:23:53 INFO - 1500938633535 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:53 INFO - 1500938633536 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633539 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633543 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9b7f5276-5227-48fb-a773-28dedec10572","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633544 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
16:23:53 INFO - 1500938633545 Marionette TRACE 390 <- [1,2,null,{}]
16:23:53 INFO - 1500938633546 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:23:53 INFO - 1500938633546 Marionette TRACE 390 <- [1,3,null,{}]
16:23:53 INFO - 1500938633547 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
16:23:53 INFO - 1500938633548 Marionette TRACE 390 <- [1,4,null,{}]
16:23:53 INFO - 1500938633550 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}]
16:23:53 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500938633553 Marionette TRACE 390 <- [1,5,null,{"value":null}]
16:23:53 INFO - 1500938633554 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
16:23:53 INFO - 1500938633556 Marionette TRACE 390 <- [1,6,null,{}]
16:23:53 INFO - 1500938633558 Marionette DEBUG Closed connection 390
16:23:53 INFO - 1500938633558 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50056
16:23:53 INFO - 1500938633559 Marionette DEBUG Closed connection 391
16:23:53 INFO - 1500938633560 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50057
16:23:53 INFO - 1500938633561 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:53 INFO - 1500938633562 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633565 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633569 Marionette TRACE 392 <- [1,1,null,{"sessionId":"8c6b59d8-436c-43c0-8b24-34e03fc9cf6c","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633571 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
16:23:53 INFO - 1500938633572 Marionette TRACE 392 <- [1,2,null,{}]
16:23:53 INFO - 1500938633574 Marionette DEBUG Closed connection 392
16:23:53 INFO - 1500938633575 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50058
16:23:53 INFO - 1500938633576 Marionette DEBUG Closed connection 393
16:23:53 INFO - 1500938633577 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50059
16:23:53 INFO - 1500938633578 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:23:53 INFO - 1500938633579 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633582 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633585 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c001fcf4-0b8d-44d1-a4d6-958105a9c301","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633587 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"}]
16:23:53 INFO - 1500938633589 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:23:53 INFO - 1500938633592 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:23:53 INFO - 1500938633592 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:23:53 INFO - 1500938633602 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"
16:23:53 INFO - 1500938633604 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"
16:23:53 INFO - 1500938633613 Marionette TRACE 394 <- [1,2,null,{}]
16:23:53 INFO - 1500938633624 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
16:23:53 INFO - 1500938633627 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"00f33fb5-3680-48f0-8b46-b01e5e7cd4c0","ELEMENT":"00f33fb5-3680-48f0-8b46-b01e5e7cd4c0"}}]
16:23:53 INFO - 1500938633628 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
16:23:53 INFO - 1500938633631 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7551ccc0-544a-46ed-a6cb-d753282c046c","ELEMENT":"7551ccc0-544a-46ed-a6cb-d753282c046c"}}]
16:23:53 INFO - 1500938633632 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"00f33fb5-3680-48f0-8b46-b01e5e7cd4c0"}]
16:23:53 INFO - 1500938633876 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:23:53 INFO - 1500938633877 Marionette TRACE 394 <- [1,5,null,{}]
16:23:53 INFO - 1500938633878 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"7551ccc0-544a-46ed-a6cb-d753282c046c"}]
16:23:53 INFO - 1500938633897 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
16:23:53 INFO - 1500938633900 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
16:23:53 INFO - 1500938633903 Marionette TRACE 394 <- [1,7,null,{"value":false}]
16:23:53 INFO - 1500938633905 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}]
16:23:53 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500938633908 Marionette TRACE 394 <- [1,8,null,{"value":null}]
16:23:53 INFO - 1500938633909 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
16:23:53 INFO - 1500938633911 Marionette TRACE 394 <- [1,9,null,{}]
16:23:53 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 381ms
16:23:53 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
16:23:53 INFO - 1500938633913 Marionette DEBUG Closed connection 394
16:23:53 INFO - 1500938633914 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50060
16:23:53 INFO - 1500938633915 Marionette DEBUG Closed connection 395
16:23:53 INFO - 1500938633916 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50061
16:23:53 INFO - 1500938633917 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:53 INFO - 1500938633917 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633921 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633924 Marionette TRACE 396 <- [1,1,null,{"sessionId":"f1cda050-1ecc-4bdd-b25f-e34a516509c5","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633926 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
16:23:53 INFO - 1500938633926 Marionette TRACE 396 <- [1,2,null,{}]
16:23:53 INFO - 1500938633927 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:23:53 INFO - 1500938633928 Marionette TRACE 396 <- [1,3,null,{}]
16:23:53 INFO - 1500938633929 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
16:23:53 INFO - 1500938633929 Marionette TRACE 396 <- [1,4,null,{}]
16:23:53 INFO - 1500938633931 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}]
16:23:53 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500938633935 Marionette TRACE 396 <- [1,5,null,{"value":null}]
16:23:53 INFO - 1500938633936 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
16:23:53 INFO - 1500938633938 Marionette TRACE 396 <- [1,6,null,{}]
16:23:53 INFO - 1500938633939 Marionette DEBUG Closed connection 396
16:23:53 INFO - 1500938633940 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50062
16:23:53 INFO - 1500938633941 Marionette DEBUG Closed connection 397
16:23:53 INFO - 1500938633942 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50063
16:23:53 INFO - 1500938633943 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:23:53 INFO - 1500938633944 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633947 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633951 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a16d4e64-91eb-4e04-9eb0-8ead0ee21fd7","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633952 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
16:23:53 INFO - 1500938633954 Marionette TRACE 398 <- [1,2,null,{}]
16:23:53 INFO - 1500938633956 Marionette DEBUG Closed connection 398
16:23:53 INFO - 1500938633957 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50064
16:23:53 INFO - 1500938633958 Marionette DEBUG Closed connection 399
16:23:53 INFO - 1500938633958 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50065
16:23:53 INFO - 1500938633960 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:23:53 INFO - 1500938633960 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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}
16:23:53 INFO - 1500938633964 Marionette DEBUG Register listener.js for window 4294967297
16:23:53 INFO - 1500938633967 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d1a9da81-2e15-4966-9a97-ab799cae09c5","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":3636,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp9dysf6.mozrunner","moz:accessibilityChecks":false}}]
16:23:53 INFO - 1500938633969 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"}]
16:23:53 INFO - 1500938633971 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"
16:23:53 INFO - 1500938633973 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"
16:23:53 INFO - 1500938633974 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"
16:23:53 INFO - 1500938633984 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"
16:23:53 INFO - 1500938633986 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"
16:23:53 INFO - 1500938633996 Marionette TRACE 400 <- [1,2,null,{}]
16:23:54 INFO - 1500938634006 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
16:23:54 INFO - 1500938634010 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9fadd910-d29c-47d8-a19b-978c271bc508","ELEMENT":"9fadd910-d29c-47d8-a19b-978c271bc508"}}]
16:23:54 INFO - 1500938634011 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9fadd910-d29c-47d8-a19b-978c271bc508"}]
16:23:54 INFO - 1500938634036 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
16:23:54 INFO - 1500938634037 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9fadd910-d29c-47d8-a19b-978c271bc508"}]
16:23:54 INFO - 1500938634056 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element