could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:24:48 INFO - 1500200688754 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:24:48 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500200688758 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:24:48 INFO - 1500200688760 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:24:48 INFO - 1500200688762 Marionette TRACE 352 <- [1,6,null,{}]
03:24:48 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 190ms
03:24:48 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:24:48 INFO - 1500200688764 Marionette DEBUG Closed connection 352
03:24:48 INFO - 1500200688765 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50119
03:24:48 INFO - 1500200688767 Marionette DEBUG Closed connection 353
03:24:48 INFO - 1500200688768 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50120
03:24:48 INFO - 1500200688769 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:48 INFO - 1500200688769 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:48 INFO - 1500200688772 Marionette DEBUG Register listener.js for window 15
03:24:48 INFO - 1500200688776 Marionette TRACE 354 <- [1,1,null,{"sessionId":"df97394a-4149-4696-80ba-5f4eaadca9bd","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:48 INFO - 1500200688778 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:24:48 INFO - 1500200688778 Marionette TRACE 354 <- [1,2,null,{}]
03:24:48 INFO - 1500200688779 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:24:48 INFO - 1500200688780 Marionette TRACE 354 <- [1,3,null,{}]
03:24:48 INFO - 1500200688781 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:24:48 INFO - 1500200688781 Marionette TRACE 354 <- [1,4,null,{}]
03:24:48 INFO - 1500200688783 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:24:48 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500200688786 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:24:48 INFO - 1500200688787 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:24:48 INFO - 1500200688789 Marionette TRACE 354 <- [1,6,null,{}]
03:24:48 INFO - 1500200688791 Marionette DEBUG Closed connection 354
03:24:48 INFO - 1500200688792 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50121
03:24:48 INFO - 1500200688792 Marionette DEBUG Closed connection 355
03:24:48 INFO - 1500200688793 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50122
03:24:48 INFO - 1500200688794 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:48 INFO - 1500200688795 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:48 INFO - 1500200688797 Marionette DEBUG Register listener.js for window 15
03:24:48 INFO - 1500200688808 Marionette TRACE 356 <- [1,1,null,{"sessionId":"9698ad55-b077-448d-bb29-e503d3936b77","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:48 INFO - 1500200688811 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:24:48 INFO - 1500200688813 Marionette TRACE 356 <- [1,2,null,{}]
03:24:48 INFO - 1500200688815 Marionette DEBUG Closed connection 356
03:24:48 INFO - 1500200688817 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50123
03:24:48 INFO - 1500200688819 Marionette DEBUG Closed connection 357
03:24:48 INFO - 1500200688819 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50124
03:24:48 INFO - 1500200688820 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:24:48 INFO - 1500200688821 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:48 INFO - 1500200688824 Marionette DEBUG Register listener.js for window 15
03:24:48 INFO - 1500200688827 Marionette TRACE 358 <- [1,1,null,{"sessionId":"bf4de741-bb41-4146-993d-23677b178c50","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:48 INFO - 1500200688830 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49675/clicks.html"}]
03:24:48 INFO - 1500200688832 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49675/hidden.html"
03:24:48 INFO - 1500200688838 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49675/hidden.html"
03:24:48 INFO - 1500200688839 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49675/hidden.html"
03:24:48 INFO - 1500200688865 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49675/clicks.html"
03:24:48 INFO - 1500200688867 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49675/clicks.html"
03:24:48 INFO - 1500200688872 Marionette TRACE 358 <- [1,2,null,{}]
03:24:48 INFO - 1500200688873 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:24:48 INFO - 1500200688884 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1255c8d7-9116-4802-a9e4-85ba6ce500e2","ELEMENT":"1255c8d7-9116-4802-a9e4-85ba6ce500e2"}}]
03:24:48 INFO - 1500200688885 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"1255c8d7-9116-4802-a9e4-85ba6ce500e2"}]
03:24:48 INFO - 1500200688932 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49675/clicks.html"
03:24:48 INFO - 1500200688942 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49675/clicks.html"
03:24:48 INFO - 1500200688943 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49675/clicks.html"
03:24:48 INFO - 1500200688973 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49675/test.html"
03:24:48 INFO - 1500200688986 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49675/test.html"
03:24:48 INFO - 1500200688991 Marionette TRACE 358 <- [1,4,null,{}]
03:24:49 INFO - 1500200688999 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:24:49 INFO - 1500200689002 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1533867e-cf93-417c-a8ef-56b1386c8f18","ELEMENT":"1533867e-cf93-417c-a8ef-56b1386c8f18"}}]
03:24:49 INFO - 1500200689003 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:24:49 INFO - 1500200689004 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:24:49 INFO - 1500200689006 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:24:49 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500200689009 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:24:49 INFO - 1500200689011 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:24:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 250ms
03:24:49 INFO - TEST-START | test_click.py TestClick.test_container_element
03:24:49 INFO - 1500200689012 Marionette TRACE 358 <- [1,8,null,{}]
03:24:49 INFO - 1500200689016 Marionette DEBUG Closed connection 358
03:24:49 INFO - 1500200689017 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50127
03:24:49 INFO - 1500200689018 Marionette DEBUG Closed connection 359
03:24:49 INFO - 1500200689018 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50128
03:24:49 INFO - 1500200689019 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:49 INFO - 1500200689020 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689023 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689028 Marionette TRACE 360 <- [1,1,null,{"sessionId":"aeb56483-ae46-4fac-b398-b2fe720b72c7","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689030 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:24:49 INFO - 1500200689030 Marionette TRACE 360 <- [1,2,null,{}]
03:24:49 INFO - 1500200689032 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:24:49 INFO - 1500200689033 Marionette TRACE 360 <- [1,3,null,{}]
03:24:49 INFO - 1500200689034 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:24:49 INFO - 1500200689034 Marionette TRACE 360 <- [1,4,null,{}]
03:24:49 INFO - 1500200689036 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:24:49 INFO - TEST-START: test_click.py TestClick.test_container_element1500200689039 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:24:49 INFO - 1500200689040 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:24:49 INFO - 1500200689042 Marionette TRACE 360 <- [1,6,null,{}]
03:24:49 INFO - 1500200689045 Marionette DEBUG Closed connection 360
03:24:49 INFO - 1500200689046 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50129
03:24:49 INFO - 1500200689047 Marionette DEBUG Closed connection 361
03:24:49 INFO - 1500200689048 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50130
03:24:49 INFO - 1500200689049 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:49 INFO - 1500200689049 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689052 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689055 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ecb2f069-c031-4097-a038-7bc02ad0f2c7","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689058 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:24:49 INFO - 1500200689060 Marionette TRACE 362 <- [1,2,null,{}]
03:24:49 INFO - 1500200689063 Marionette DEBUG Closed connection 362
03:24:49 INFO - 1500200689064 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50131
03:24:49 INFO - 1500200689065 Marionette DEBUG Closed connection 363
03:24:49 INFO - 1500200689066 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50132
03:24:49 INFO - 1500200689067 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:24:49 INFO - 1500200689067 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689070 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689073 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1222be21-b4fe-4c01-856f-33222e4ba199","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689077 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:24:49 INFO - 1500200689079 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49675/test.html"
03:24:49 INFO - 1500200689081 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49675/test.html"
03:24:49 INFO - 1500200689081 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49675/test.html"
03:24:49 INFO - 1500200689107 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:24:49 INFO - 1500200689115 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:24:49 INFO - 1500200689118 Marionette TRACE 364 <- [1,2,null,{}]
03:24:49 INFO - 1500200689120 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:24:49 INFO - 1500200689122 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fc0f7b64-babd-4c43-b291-991d5377e94b","ELEMENT":"fc0f7b64-babd-4c43-b291-991d5377e94b"}}]
03:24:49 INFO - 1500200689131 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"fc0f7b64-babd-4c43-b291-991d5377e94b"}]
03:24:49 INFO - 1500200689377 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:24:49 INFO - 1500200689385 Marionette TRACE 364 <- [1,4,null,{}]
03:24:49 INFO - 1500200689387 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"fc0f7b64-babd-4c43-b291-991d5377e94b","name":"selected"}]
03:24:49 INFO - 1500200689392 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:24:49 INFO - 1500200689394 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:24:49 INFO - TEST-END: test_click.py TestClick.test_container_element1500200689397 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:24:49 INFO - 1500200689398 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:24:49 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 387ms
03:24:49 INFO - 1500200689400 Marionette TRACE 364 <- [1,7,null,{}]
03:24:49 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:24:49 INFO - 1500200689402 Marionette DEBUG Closed connection 364
03:24:49 INFO - 1500200689403 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50133
03:24:49 INFO - 1500200689450 Marionette DEBUG Closed connection 365
03:24:49 INFO - 1500200689451 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50134
03:24:49 INFO - 1500200689463 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:49 INFO - 1500200689464 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689467 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689470 Marionette TRACE 366 <- [1,1,null,{"sessionId":"7a681cec-0c9b-40c7-881d-5edca16b4b86","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689472 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:24:49 INFO - 1500200689473 Marionette TRACE 366 <- [1,2,null,{}]
03:24:49 INFO - 1500200689475 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:24:49 INFO - 1500200689476 Marionette TRACE 366 <- [1,3,null,{}]
03:24:49 INFO - 1500200689478 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:24:49 INFO - 1500200689478 Marionette TRACE 366 <- [1,4,null,{}]
03:24:49 INFO - 1500200689480 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:24:49 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500200689483 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:24:49 INFO - 1500200689484 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:24:49 INFO - 1500200689486 Marionette TRACE 366 <- [1,6,null,{}]
03:24:49 INFO - 1500200689488 Marionette DEBUG Closed connection 366
03:24:49 INFO - 1500200689489 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50135
03:24:49 INFO - 1500200689490 Marionette DEBUG Closed connection 367
03:24:49 INFO - 1500200689490 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50136
03:24:49 INFO - 1500200689493 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:49 INFO - 1500200689493 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689496 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689507 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cfc3bf9d-bb1f-483f-a54e-ebf7a94d4fcf","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689509 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:24:49 INFO - 1500200689511 Marionette TRACE 368 <- [1,2,null,{}]
03:24:49 INFO - 1500200689513 Marionette DEBUG Closed connection 368
03:24:49 INFO - 1500200689514 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50137
03:24:49 INFO - 1500200689515 Marionette DEBUG Closed connection 369
03:24:49 INFO - 1500200689516 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50138
03:24:49 INFO - 1500200689517 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:24:49 INFO - 1500200689517 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689520 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689523 Marionette TRACE 370 <- [1,1,null,{"sessionId":"90b28bd5-e27e-4dd1-bfbf-b30ce2838d19","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689525 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:24:49 INFO - 1500200689528 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:24:49 INFO - 1500200689531 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:24:49 INFO - 1500200689532 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:24:49 INFO - 1500200689551 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:24:49 INFO - 1500200689555 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:24:49 INFO - 1500200689559 Marionette TRACE 370 <- [1,2,null,{}]
03:24:49 INFO - 1500200689566 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:24:49 INFO - 1500200689569 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ffd6c269-f41b-4998-883e-53e21d617d3c","ELEMENT":"ffd6c269-f41b-4998-883e-53e21d617d3c"}}]
03:24:49 INFO - 1500200689570 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ffd6c269-f41b-4998-883e-53e21d617d3c"}]
03:24:49 INFO - 1500200689815 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:24:49 INFO - 1500200689816 Marionette TRACE 370 <- [1,4,null,{}]
03:24:49 INFO - 1500200689817 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ffd6c269-f41b-4998-883e-53e21d617d3c","name":"selected"}]
03:24:49 INFO - 1500200689822 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:24:49 INFO - 1500200689824 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:24:49 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500200689827 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:24:49 INFO - 1500200689828 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:24:49 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 429ms
03:24:49 INFO - 1500200689830 Marionette TRACE 370 <- [1,7,null,{}]
03:24:49 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:24:49 INFO - 1500200689832 Marionette DEBUG Closed connection 370
03:24:49 INFO - 1500200689833 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50139
03:24:49 INFO - 1500200689834 Marionette DEBUG Closed connection 371
03:24:49 INFO - 1500200689835 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50140
03:24:49 INFO - 1500200689836 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:49 INFO - 1500200689837 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689839 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689843 Marionette TRACE 372 <- [1,1,null,{"sessionId":"0df40c44-b71b-4f54-be36-fc7aefbb6187","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689845 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:24:49 INFO - 1500200689845 Marionette TRACE 372 <- [1,2,null,{}]
03:24:49 INFO - 1500200689846 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:24:49 INFO - 1500200689847 Marionette TRACE 372 <- [1,3,null,{}]
03:24:49 INFO - 1500200689848 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:24:49 INFO - 1500200689848 Marionette TRACE 372 <- [1,4,null,{}]
03:24:49 INFO - 1500200689850 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:24:49 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500200689853 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:24:49 INFO - 1500200689854 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:24:49 INFO - 1500200689855 Marionette TRACE 372 <- [1,6,null,{}]
03:24:49 INFO - 1500200689858 Marionette DEBUG Closed connection 372
03:24:49 INFO - 1500200689858 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50141
03:24:49 INFO - 1500200689859 Marionette DEBUG Closed connection 373
03:24:49 INFO - 1500200689860 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50142
03:24:49 INFO - 1500200689861 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:49 INFO - 1500200689862 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689864 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689867 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a76821c0-24eb-4996-83cd-5e79ffd793f3","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689869 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:24:49 INFO - 1500200689871 Marionette TRACE 374 <- [1,2,null,{}]
03:24:49 INFO - 1500200689873 Marionette DEBUG Closed connection 374
03:24:49 INFO - 1500200689874 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50143
03:24:49 INFO - 1500200689875 Marionette DEBUG Closed connection 375
03:24:49 INFO - 1500200689876 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50144
03:24:49 INFO - 1500200689877 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:24:49 INFO - 1500200689878 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:49 INFO - 1500200689880 Marionette DEBUG Register listener.js for window 15
03:24:49 INFO - 1500200689883 Marionette TRACE 376 <- [1,1,null,{"sessionId":"18c1858f-8a19-4573-bb99-dc9abd635ef1","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:49 INFO - 1500200689885 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:24:49 INFO - 1500200689887 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:24:49 INFO - 1500200689890 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:24:49 INFO - 1500200689890 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:24:49 INFO - 1500200689912 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:24:49 INFO - 1500200689915 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:24:49 INFO - 1500200689919 Marionette TRACE 376 <- [1,2,null,{}]
03:24:49 INFO - 1500200689920 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:24:49 INFO - 1500200689922 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f22c9892-935f-49e4-acf1-40ce1583f991","ELEMENT":"f22c9892-935f-49e4-acf1-40ce1583f991"}}]
03:24:49 INFO - 1500200689923 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f22c9892-935f-49e4-acf1-40ce1583f991"}]
03:24:50 INFO - 1500200690172 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:24:50 INFO - 1500200690173 Marionette TRACE 376 <- [1,4,null,{}]
03:24:50 INFO - 1500200690175 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:24:50 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500200690178 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:24:50 INFO - 1500200690179 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:24:50 INFO - 1500200690181 Marionette TRACE 376 <- [1,6,null,{}]
03:24:50 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 350ms
03:24:50 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:24:50 INFO - 1500200690183 Marionette DEBUG Closed connection 376
03:24:50 INFO - 1500200690184 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50145
03:24:50 INFO - 1500200690185 Marionette DEBUG Closed connection 377
03:24:50 INFO - 1500200690186 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50146
03:24:50 INFO - 1500200690187 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:50 INFO - 1500200690187 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690190 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690194 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5073d58f-aa1c-4860-bd60-755d005db645","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690196 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:24:50 INFO - 1500200690197 Marionette TRACE 378 <- [1,2,null,{}]
03:24:50 INFO - 1500200690198 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:24:50 INFO - 1500200690198 Marionette TRACE 378 <- [1,3,null,{}]
03:24:50 INFO - 1500200690199 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:24:50 INFO - 1500200690200 Marionette TRACE 378 <- [1,4,null,{}]
03:24:50 INFO - 1500200690202 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:24:50 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500200690204 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:24:50 INFO - 1500200690205 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:24:50 INFO - 1500200690207 Marionette TRACE 378 <- [1,6,null,{}]
03:24:50 INFO - 1500200690209 Marionette DEBUG Closed connection 378
03:24:50 INFO - 1500200690210 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50147
03:24:50 INFO - 1500200690211 Marionette DEBUG Closed connection 379
03:24:50 INFO - 1500200690212 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50148
03:24:50 INFO - 1500200690213 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:50 INFO - 1500200690213 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690216 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690219 Marionette TRACE 380 <- [1,1,null,{"sessionId":"2917f84f-719b-403f-8dab-bed8fc2e51fd","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690221 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:24:50 INFO - 1500200690223 Marionette TRACE 380 <- [1,2,null,{}]
03:24:50 INFO - 1500200690226 Marionette DEBUG Closed connection 380
03:24:50 INFO - 1500200690227 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50149
03:24:50 INFO - 1500200690227 Marionette DEBUG Closed connection 381
03:24:50 INFO - 1500200690228 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50150
03:24:50 INFO - 1500200690229 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:24:50 INFO - 1500200690230 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690240 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690244 Marionette TRACE 382 <- [1,1,null,{"sessionId":"92114ca9-3fc9-496b-bcea-6fa38c9c28bb","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690247 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:24:50 INFO - 1500200690248 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:24:50 INFO - 1500200690251 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:24:50 INFO - 1500200690251 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:24:50 INFO - 1500200690270 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:24:50 INFO - 1500200690274 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:24:50 INFO - 1500200690286 Marionette TRACE 382 <- [1,2,null,{}]
03:24:50 INFO - 1500200690287 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:24:50 INFO - 1500200690290 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a481c66-4bc8-4b36-b406-9b8d785a5e3b","ELEMENT":"9a481c66-4bc8-4b36-b406-9b8d785a5e3b"}}]
03:24:50 INFO - 1500200690291 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"9a481c66-4bc8-4b36-b406-9b8d785a5e3b"}]
03:24:50 INFO - 1500200690535 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:24:50 INFO - 1500200690537 Marionette TRACE 382 <- [1,4,null,{}]
03:24:50 INFO - 1500200690538 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:24:50 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500200690542 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:24:50 INFO - 1500200690542 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:24:50 INFO - 1500200690544 Marionette TRACE 382 <- [1,6,null,{}]
03:24:50 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 363ms
03:24:50 INFO - TEST-START | test_click.py TestClick.test_input_file
03:24:50 INFO - 1500200690546 Marionette DEBUG Closed connection 382
03:24:50 INFO - 1500200690547 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50151
03:24:50 INFO - 1500200690548 Marionette DEBUG Closed connection 383
03:24:50 INFO - 1500200690549 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50152
03:24:50 INFO - 1500200690550 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:50 INFO - 1500200690551 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690553 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690557 Marionette TRACE 384 <- [1,1,null,{"sessionId":"aff091c8-a08c-4cc9-9a57-3c2bd6bc8aad","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690559 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:24:50 INFO - 1500200690559 Marionette TRACE 384 <- [1,2,null,{}]
03:24:50 INFO - 1500200690560 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:24:50 INFO - 1500200690561 Marionette TRACE 384 <- [1,3,null,{}]
03:24:50 INFO - 1500200690562 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:24:50 INFO - 1500200690562 Marionette TRACE 384 <- [1,4,null,{}]
03:24:50 INFO - 1500200690564 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:24:50 INFO - TEST-START: test_click.py TestClick.test_input_file1500200690567 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:24:50 INFO - 1500200690568 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:24:50 INFO - 1500200690569 Marionette TRACE 384 <- [1,6,null,{}]
03:24:50 INFO - 1500200690572 Marionette DEBUG Closed connection 384
03:24:50 INFO - 1500200690572 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50153
03:24:50 INFO - 1500200690573 Marionette DEBUG Closed connection 385
03:24:50 INFO - 1500200690574 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50154
03:24:50 INFO - 1500200690575 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:50 INFO - 1500200690576 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690578 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690581 Marionette TRACE 386 <- [1,1,null,{"sessionId":"67ebce81-094c-42fb-8ac6-06d2f9d9f927","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690584 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:24:50 INFO - 1500200690585 Marionette TRACE 386 <- [1,2,null,{}]
03:24:50 INFO - 1500200690587 Marionette DEBUG Closed connection 386
03:24:50 INFO - 1500200690588 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50155
03:24:50 INFO - 1500200690589 Marionette DEBUG Closed connection 387
03:24:50 INFO - 1500200690589 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50156
03:24:50 INFO - 1500200690591 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:24:50 INFO - 1500200690591 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690594 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690597 Marionette TRACE 388 <- [1,1,null,{"sessionId":"6c4d11cf-328e-46ca-a1fc-28548fd4e85e","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690599 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:24:50 INFO - 1500200690601 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:24:50 INFO - 1500200690603 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:24:50 INFO - 1500200690604 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:24:50 INFO - 1500200690624 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:24:50 INFO - 1500200690631 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:24:50 INFO - 1500200690634 Marionette TRACE 388 <- [1,2,null,{}]
03:24:50 INFO - 1500200690636 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:24:50 INFO - 1500200690639 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f42ca71-3b7f-456c-91b3-ee40e591a7c4","ELEMENT":"4f42ca71-3b7f-456c-91b3-ee40e591a7c4"}}]
03:24:50 INFO - 1500200690640 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4f42ca71-3b7f-456c-91b3-ee40e591a7c4"}]
03:24:50 INFO - 1500200690676 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:24:50 INFO - 1500200690679 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:24:50 INFO - TEST-END: test_click.py TestClick.test_input_file1500200690682 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:24:50 INFO - 1500200690683 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:24:50 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 140ms
03:24:50 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:24:50 INFO - 1500200690684 Marionette TRACE 388 <- [1,6,null,{}]
03:24:50 INFO - 1500200690687 Marionette DEBUG Closed connection 388
03:24:50 INFO - 1500200690688 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50157
03:24:50 INFO - 1500200690689 Marionette DEBUG Closed connection 389
03:24:50 INFO - 1500200690689 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50158
03:24:50 INFO - 1500200690690 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:50 INFO - 1500200690691 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690694 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690697 Marionette TRACE 390 <- [1,1,null,{"sessionId":"698d3b15-512c-45f3-9725-8f1df09da964","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690699 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:24:50 INFO - 1500200690700 Marionette TRACE 390 <- [1,2,null,{}]
03:24:50 INFO - 1500200690701 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:24:50 INFO - 1500200690701 Marionette TRACE 390 <- [1,3,null,{}]
03:24:50 INFO - 1500200690702 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:24:50 INFO - 1500200690703 Marionette TRACE 390 <- [1,4,null,{}]
03:24:50 INFO - 1500200690705 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:24:50 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500200690708 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:24:50 INFO - 1500200690709 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:24:50 INFO - 1500200690710 Marionette TRACE 390 <- [1,6,null,{}]
03:24:50 INFO - 1500200690712 Marionette DEBUG Closed connection 390
03:24:50 INFO - 1500200690713 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50159
03:24:50 INFO - 1500200690714 Marionette DEBUG Closed connection 391
03:24:50 INFO - 1500200690715 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50160
03:24:50 INFO - 1500200690716 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:50 INFO - 1500200690716 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690719 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690722 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f2300683-5632-4788-b655-3915320bbeaf","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690724 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:24:50 INFO - 1500200690726 Marionette TRACE 392 <- [1,2,null,{}]
03:24:50 INFO - 1500200690728 Marionette DEBUG Closed connection 392
03:24:50 INFO - 1500200690728 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50161
03:24:50 INFO - 1500200690729 Marionette DEBUG Closed connection 393
03:24:50 INFO - 1500200690730 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50162
03:24:50 INFO - 1500200690731 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:24:50 INFO - 1500200690732 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:50 INFO - 1500200690734 Marionette DEBUG Register listener.js for window 15
03:24:50 INFO - 1500200690744 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2a5df6fb-9105-4fb4-958e-c0b22fbd0a90","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:50 INFO - 1500200690747 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:24:50 INFO - 1500200690748 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:24:50 INFO - 1500200690751 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:24:50 INFO - 1500200690751 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:24:50 INFO - 1500200690773 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:24:50 INFO - 1500200690776 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:24:50 INFO - 1500200690787 Marionette TRACE 394 <- [1,2,null,{}]
03:24:50 INFO - 1500200690788 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:24:50 INFO - 1500200690791 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2c515090-1d2b-42a7-8150-e4a5e9f17483","ELEMENT":"2c515090-1d2b-42a7-8150-e4a5e9f17483"}}]
03:24:50 INFO - 1500200690794 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:24:50 INFO - 1500200690797 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b11d08f8-3e02-4af0-b18c-b93a98dba85a","ELEMENT":"b11d08f8-3e02-4af0-b18c-b93a98dba85a"}}]
03:24:50 INFO - 1500200690798 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2c515090-1d2b-42a7-8150-e4a5e9f17483"}]
03:24:51 INFO - 1500200691037 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:24:51 INFO - 1500200691038 Marionette TRACE 394 <- [1,5,null,{}]
03:24:51 INFO - 1500200691039 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b11d08f8-3e02-4af0-b18c-b93a98dba85a"}]
03:24:51 INFO - 1500200691057 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
03:24:51 INFO - 1500200691059 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:24:51 INFO - 1500200691062 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:24:51 INFO - 1500200691063 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:24:51 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500200691066 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:24:51 INFO - 1500200691067 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:24:51 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 384ms
03:24:51 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:24:51 INFO - 1500200691069 Marionette TRACE 394 <- [1,9,null,{}]
03:24:51 INFO - 1500200691071 Marionette DEBUG Closed connection 394
03:24:51 INFO - 1500200691072 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50163
03:24:51 INFO - 1500200691073 Marionette DEBUG Closed connection 395
03:24:51 INFO - 1500200691073 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50164
03:24:51 INFO - 1500200691075 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:51 INFO - 1500200691075 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:51 INFO - 1500200691078 Marionette DEBUG Register listener.js for window 15
03:24:51 INFO - 1500200691082 Marionette TRACE 396 <- [1,1,null,{"sessionId":"e2f70916-4f88-4ff6-b595-4c32d835dcbf","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:51 INFO - 1500200691084 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:24:51 INFO - 1500200691084 Marionette TRACE 396 <- [1,2,null,{}]
03:24:51 INFO - 1500200691085 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:24:51 INFO - 1500200691086 Marionette TRACE 396 <- [1,3,null,{}]
03:24:51 INFO - 1500200691087 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:24:51 INFO - 1500200691087 Marionette TRACE 396 <- [1,4,null,{}]
03:24:51 INFO - 1500200691089 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:24:51 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500200691093 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:24:51 INFO - 1500200691095 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:24:51 INFO - 1500200691096 Marionette TRACE 396 <- [1,6,null,{}]
03:24:51 INFO - 1500200691098 Marionette DEBUG Closed connection 396
03:24:51 INFO - 1500200691099 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50165
03:24:51 INFO - 1500200691100 Marionette DEBUG Closed connection 397
03:24:51 INFO - 1500200691101 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50166
03:24:51 INFO - 1500200691102 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:24:51 INFO - 1500200691103 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:51 INFO - 1500200691105 Marionette DEBUG Register listener.js for window 15
03:24:51 INFO - 1500200691108 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b5e97c12-fc35-49de-bd36-ee17cb4c2303","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:51 INFO - 1500200691110 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:24:51 INFO - 1500200691112 Marionette TRACE 398 <- [1,2,null,{}]
03:24:51 INFO - 1500200691114 Marionette DEBUG Closed connection 398
03:24:51 INFO - 1500200691115 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50167
03:24:51 INFO - 1500200691116 Marionette DEBUG Closed connection 399
03:24:51 INFO - 1500200691117 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50168
03:24:51 INFO - 1500200691120 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:24:51 INFO - 1500200691121 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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}
03:24:51 INFO - 1500200691123 Marionette DEBUG Register listener.js for window 15
03:24:51 INFO - 1500200691127 Marionette TRACE 400 <- [1,1,null,{"sessionId":"9d2ddcb3-fd91-48a3-99e4-bba0bc55d79c","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":3156,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpr4nut7.mozrunner","moz:accessibilityChecks":false}}]
03:24:51 INFO - 1500200691129 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:24:51 INFO - 1500200691131 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:24:51 INFO - 1500200691133 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:24:51 INFO - 1500200691134 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:24:51 INFO - 1500200691154 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:24:51 INFO - 1500200691158 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:24:51 INFO - 1500200691168 Marionette TRACE 400 <- [1,2,null,{}]
03:24:51 INFO - 1500200691170 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:24:51 INFO - 1500200691172 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e1666ab8-be81-4dff-b9d4-672b7f4e126d","ELEMENT":"e1666ab8-be81-4dff-b9d4-672b7f4e126d"}}]
03:24:51 INFO - 1500200691173 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e1666ab8-be81-4dff-b9d4-672b7f4e126d"}]
03:24:51 INFO - 1500200691197 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:24:51 INFO - 1500200691206 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e1666ab8-be81-4dff-b9d4-672b7f4e126d"}]
03:24:51 INFO - 1500200691228 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element