could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
18:24:40 INFO - 1499131480789 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}]
18:24:40 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499131480791 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:24:40 INFO - 1499131480792 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:24:40 INFO - 1499131480793 Marionette TRACE 352 <- [1,6,null,{}]
18:24:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 146ms
18:24:40 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:24:40 INFO - 1499131480795 Marionette DEBUG Closed connection 352
18:24:40 INFO - 1499131480796 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50018
18:24:40 INFO - 1499131480797 Marionette DEBUG Closed connection 353
18:24:40 INFO - 1499131480797 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50019
18:24:40 INFO - 1499131480798 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:40 INFO - 1499131480799 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:40 INFO - 1499131480801 Marionette DEBUG Register listener.js for window 12
18:24:40 INFO - 1499131480804 Marionette TRACE 354 <- [1,1,null,{"sessionId":"73fb2759-e891-4086-8b60-dcd09a92943c","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:40 INFO - 1499131480806 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:24:40 INFO - 1499131480806 Marionette TRACE 354 <- [1,2,null,{}]
18:24:40 INFO - 1499131480807 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:24:40 INFO - 1499131480808 Marionette TRACE 354 <- [1,3,null,{}]
18:24:40 INFO - 1499131480808 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:24:40 INFO - 1499131480809 Marionette TRACE 354 <- [1,4,null,{}]
18:24:40 INFO - 1499131480811 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}]
18:24:40 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499131480813 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:24:40 INFO - 1499131480814 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:24:40 INFO - 1499131480815 Marionette TRACE 354 <- [1,6,null,{}]
18:24:40 INFO - 1499131480816 Marionette DEBUG Closed connection 354
18:24:40 INFO - 1499131480817 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50020
18:24:40 INFO - 1499131480818 Marionette DEBUG Closed connection 355
18:24:40 INFO - 1499131480819 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50021
18:24:40 INFO - 1499131480819 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:40 INFO - 1499131480821 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:40 INFO - 1499131480822 Marionette DEBUG Register listener.js for window 12
18:24:40 INFO - 1499131480839 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f6315d2c-3fdd-4079-8830-58548829887b","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:40 INFO - 1499131480840 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:24:40 INFO - 1499131480841 Marionette TRACE 356 <- [1,2,null,{}]
18:24:40 INFO - 1499131480843 Marionette DEBUG Closed connection 356
18:24:40 INFO - 1499131480844 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50022
18:24:40 INFO - 1499131480844 Marionette DEBUG Closed connection 357
18:24:40 INFO - 1499131480845 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50023
18:24:40 INFO - 1499131480846 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:24:40 INFO - 1499131480847 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:40 INFO - 1499131480848 Marionette DEBUG Register listener.js for window 12
18:24:40 INFO - 1499131480851 Marionette TRACE 358 <- [1,1,null,{"sessionId":"7e5159fe-7439-4030-a89c-31f8c87894d3","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:40 INFO - 1499131480852 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49590/clicks.html"}]
18:24:40 INFO - 1499131480854 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49590/hidden.html"
18:24:40 INFO - 1499131480861 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49590/hidden.html"
18:24:40 INFO - 1499131480862 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49590/hidden.html"
18:24:40 INFO - 1499131480879 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49590/clicks.html"
18:24:40 INFO - 1499131480881 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49590/clicks.html"
18:24:40 INFO - 1499131480885 Marionette TRACE 358 <- [1,2,null,{}]
18:24:40 INFO - 1499131480886 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:24:40 INFO - 1499131480889 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b0ff193a-7e1f-4779-baf8-458d050f2847","ELEMENT":"b0ff193a-7e1f-4779-baf8-458d050f2847"}}]
18:24:40 INFO - 1499131480890 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"b0ff193a-7e1f-4779-baf8-458d050f2847"}]
18:24:40 INFO - 1499131480950 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49590/clicks.html"
18:24:40 INFO - 1499131480958 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49590/clicks.html"
18:24:40 INFO - 1499131480959 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49590/clicks.html"
18:24:40 INFO - 1499131480982 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49590/test.html"
18:24:40 INFO - 1499131480983 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49590/test.html"
18:24:40 INFO - 1499131480986 Marionette TRACE 358 <- [1,4,null,{}]
18:24:40 INFO - 1499131480988 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:24:41 INFO - 1499131480998 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2e542411-e737-481a-b9af-c4359dd5e73c","ELEMENT":"2e542411-e737-481a-b9af-c4359dd5e73c"}}]
18:24:41 INFO - 1499131481000 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:24:41 INFO - 1499131481001 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:24:41 INFO - 1499131481003 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}]
18:24:41 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499131481005 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:24:41 INFO - 1499131481006 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:24:41 INFO - 1499131481008 Marionette TRACE 358 <- [1,8,null,{}]
18:24:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 215ms
18:24:41 INFO - TEST-START | test_click.py TestClick.test_container_element
18:24:41 INFO - 1499131481010 Marionette DEBUG Closed connection 358
18:24:41 INFO - 1499131481011 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50026
18:24:41 INFO - 1499131481012 Marionette DEBUG Closed connection 359
18:24:41 INFO - 1499131481013 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50027
18:24:41 INFO - 1499131481014 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:41 INFO - 1499131481015 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481017 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481020 Marionette TRACE 360 <- [1,1,null,{"sessionId":"600ffa30-5792-4e5d-8508-a889a6734cd3","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481021 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:24:41 INFO - 1499131481021 Marionette TRACE 360 <- [1,2,null,{}]
18:24:41 INFO - 1499131481022 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:24:41 INFO - 1499131481022 Marionette TRACE 360 <- [1,3,null,{}]
18:24:41 INFO - 1499131481026 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:24:41 INFO - 1499131481027 Marionette TRACE 360 <- [1,4,null,{}]
18:24:41 INFO - 1499131481028 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}]
18:24:41 INFO - TEST-START: test_click.py TestClick.test_container_element1499131481031 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:24:41 INFO - 1499131481032 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:24:41 INFO - 1499131481033 Marionette TRACE 360 <- [1,6,null,{}]
18:24:41 INFO - 1499131481035 Marionette DEBUG Closed connection 360
18:24:41 INFO - 1499131481036 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50028
18:24:41 INFO - 1499131481037 Marionette DEBUG Closed connection 361
18:24:41 INFO - 1499131481037 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50029
18:24:41 INFO - 1499131481038 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:41 INFO - 1499131481039 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481041 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481045 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5c0d5454-f45f-4583-b953-a97f3c36abfc","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481046 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:24:41 INFO - 1499131481047 Marionette TRACE 362 <- [1,2,null,{}]
18:24:41 INFO - 1499131481049 Marionette DEBUG Closed connection 362
18:24:41 INFO - 1499131481049 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50030
18:24:41 INFO - 1499131481050 Marionette DEBUG Closed connection 363
18:24:41 INFO - 1499131481051 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50031
18:24:41 INFO - 1499131481052 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:24:41 INFO - 1499131481052 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481054 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481057 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9a907bc2-6450-4260-9969-37241f8e6d93","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481059 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"}]
18:24:41 INFO - 1499131481060 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49590/test.html"
18:24:41 INFO - 1499131481064 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49590/test.html"
18:24:41 INFO - 1499131481064 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49590/test.html"
18:24:41 INFO - 1499131481091 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"
18:24:41 INFO - 1499131481101 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"
18:24:41 INFO - 1499131481106 Marionette TRACE 364 <- [1,2,null,{}]
18:24:41 INFO - 1499131481114 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:24:41 INFO - 1499131481117 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e50e5a09-bddf-4f20-9ccb-7435189b4957","ELEMENT":"e50e5a09-bddf-4f20-9ccb-7435189b4957"}}]
18:24:41 INFO - 1499131481119 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e50e5a09-bddf-4f20-9ccb-7435189b4957"}]
18:24:41 INFO - 1499131481357 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:24:41 INFO - 1499131481358 Marionette TRACE 364 <- [1,4,null,{}]
18:24:41 INFO - 1499131481359 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e50e5a09-bddf-4f20-9ccb-7435189b4957","name":"selected"}]
18:24:41 INFO - 1499131481363 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:24:41 INFO - 1499131481365 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}]
18:24:41 INFO - TEST-END: test_click.py TestClick.test_container_element1499131481367 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:24:41 INFO - 1499131481368 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:24:41 INFO - 1499131481369 Marionette TRACE 364 <- [1,7,null,{}]
18:24:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 361ms
18:24:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:24:41 INFO - 1499131481371 Marionette DEBUG Closed connection 364
18:24:41 INFO - 1499131481372 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50032
18:24:41 INFO - 1499131481373 Marionette DEBUG Closed connection 365
18:24:41 INFO - 1499131481374 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50033
18:24:41 INFO - 1499131481375 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:41 INFO - 1499131481375 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481377 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481380 Marionette TRACE 366 <- [1,1,null,{"sessionId":"c4f51860-36c1-40d1-b423-9a4e8b2548f5","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481381 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:24:41 INFO - 1499131481382 Marionette TRACE 366 <- [1,2,null,{}]
18:24:41 INFO - 1499131481382 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:24:41 INFO - 1499131481383 Marionette TRACE 366 <- [1,3,null,{}]
18:24:41 INFO - 1499131481384 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:24:41 INFO - 1499131481384 Marionette TRACE 366 <- [1,4,null,{}]
18:24:41 INFO - 1499131481386 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}]
18:24:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499131481388 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:24:41 INFO - 1499131481389 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:24:41 INFO - 1499131481390 Marionette TRACE 366 <- [1,6,null,{}]
18:24:41 INFO - 1499131481392 Marionette DEBUG Closed connection 366
18:24:41 INFO - 1499131481393 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50034
18:24:41 INFO - 1499131481393 Marionette DEBUG Closed connection 367
18:24:41 INFO - 1499131481394 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50035
18:24:41 INFO - 1499131481395 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:41 INFO - 1499131481396 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481398 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481401 Marionette TRACE 368 <- [1,1,null,{"sessionId":"20f531b1-adea-4f9f-8d23-675cfca332cb","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481402 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:24:41 INFO - 1499131481403 Marionette TRACE 368 <- [1,2,null,{}]
18:24:41 INFO - 1499131481404 Marionette DEBUG Closed connection 368
18:24:41 INFO - 1499131481405 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50036
18:24:41 INFO - 1499131481406 Marionette DEBUG Closed connection 369
18:24:41 INFO - 1499131481407 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50037
18:24:41 INFO - 1499131481407 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:24:41 INFO - 1499131481408 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481410 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481413 Marionette TRACE 370 <- [1,1,null,{"sessionId":"0aa2db21-4364-4917-9a00-4d8d5ce76645","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481414 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"}]
18:24:41 INFO - 1499131481415 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"
18:24:41 INFO - 1499131481417 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"
18:24:41 INFO - 1499131481418 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"
18:24:41 INFO - 1499131481433 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"
18:24:41 INFO - 1499131481436 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"
18:24:41 INFO - 1499131481439 Marionette TRACE 370 <- [1,2,null,{}]
18:24:41 INFO - 1499131481440 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:24:41 INFO - 1499131481448 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e19567a-83e2-4417-82e5-442c31d5b015","ELEMENT":"6e19567a-83e2-4417-82e5-442c31d5b015"}}]
18:24:41 INFO - 1499131481449 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"6e19567a-83e2-4417-82e5-442c31d5b015"}]
18:24:41 INFO - 1499131481693 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:24:41 INFO - 1499131481694 Marionette TRACE 370 <- [1,4,null,{}]
18:24:41 INFO - 1499131481695 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"6e19567a-83e2-4417-82e5-442c31d5b015","name":"selected"}]
18:24:41 INFO - 1499131481699 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:24:41 INFO - 1499131481701 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}]
18:24:41 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499131481703 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:24:41 INFO - 1499131481704 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:24:41 INFO - 1499131481705 Marionette TRACE 370 <- [1,7,null,{}]
18:24:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 335ms
18:24:41 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:24:41 INFO - 1499131481707 Marionette DEBUG Closed connection 370
18:24:41 INFO - 1499131481708 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50038
18:24:41 INFO - 1499131481708 Marionette DEBUG Closed connection 371
18:24:41 INFO - 1499131481709 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50039
18:24:41 INFO - 1499131481710 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:41 INFO - 1499131481711 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481713 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481715 Marionette TRACE 372 <- [1,1,null,{"sessionId":"bb007466-e59f-4316-8b30-40b8884dc2c0","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481717 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:24:41 INFO - 1499131481717 Marionette TRACE 372 <- [1,2,null,{}]
18:24:41 INFO - 1499131481718 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:24:41 INFO - 1499131481718 Marionette TRACE 372 <- [1,3,null,{}]
18:24:41 INFO - 1499131481719 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:24:41 INFO - 1499131481719 Marionette TRACE 372 <- [1,4,null,{}]
18:24:41 INFO - 1499131481721 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}]
18:24:41 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499131481723 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:24:41 INFO - 1499131481724 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:24:41 INFO - 1499131481725 Marionette TRACE 372 <- [1,6,null,{}]
18:24:41 INFO - 1499131481727 Marionette DEBUG Closed connection 372
18:24:41 INFO - 1499131481728 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50040
18:24:41 INFO - 1499131481728 Marionette DEBUG Closed connection 373
18:24:41 INFO - 1499131481729 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50041
18:24:41 INFO - 1499131481730 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:41 INFO - 1499131481730 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481732 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481735 Marionette TRACE 374 <- [1,1,null,{"sessionId":"773af579-7d35-4b6e-8e86-d3c889370bfa","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481736 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:24:41 INFO - 1499131481737 Marionette TRACE 374 <- [1,2,null,{}]
18:24:41 INFO - 1499131481739 Marionette DEBUG Closed connection 374
18:24:41 INFO - 1499131481739 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50042
18:24:41 INFO - 1499131481740 Marionette DEBUG Closed connection 375
18:24:41 INFO - 1499131481741 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50043
18:24:41 INFO - 1499131481742 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:24:41 INFO - 1499131481742 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:41 INFO - 1499131481744 Marionette DEBUG Register listener.js for window 12
18:24:41 INFO - 1499131481747 Marionette TRACE 376 <- [1,1,null,{"sessionId":"c84b27e5-5a36-4880-a81c-fce1bd89dc5d","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:41 INFO - 1499131481748 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"}]
18:24:41 INFO - 1499131481749 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"
18:24:41 INFO - 1499131481752 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"
18:24:41 INFO - 1499131481752 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"
18:24:41 INFO - 1499131481768 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"
18:24:41 INFO - 1499131481771 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"
18:24:41 INFO - 1499131481774 Marionette TRACE 376 <- [1,2,null,{}]
18:24:41 INFO - 1499131481782 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:24:41 INFO - 1499131481784 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e77d73f5-1397-48c9-b49a-233a7df2a9e6","ELEMENT":"e77d73f5-1397-48c9-b49a-233a7df2a9e6"}}]
18:24:41 INFO - 1499131481785 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e77d73f5-1397-48c9-b49a-233a7df2a9e6"}]
18:24:42 INFO - 1499131482020 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:24:42 INFO - 1499131482021 Marionette TRACE 376 <- [1,4,null,{}]
18:24:42 INFO - 1499131482023 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}]
18:24:42 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499131482025 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:24:42 INFO - 1499131482026 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:24:42 INFO - 1499131482027 Marionette TRACE 376 <- [1,6,null,{}]
18:24:42 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
18:24:42 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:24:42 INFO - 1499131482029 Marionette DEBUG Closed connection 376
18:24:42 INFO - 1499131482030 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50044
18:24:42 INFO - 1499131482031 Marionette DEBUG Closed connection 377
18:24:42 INFO - 1499131482032 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50045
18:24:42 INFO - 1499131482033 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:42 INFO - 1499131482033 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482035 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482038 Marionette TRACE 378 <- [1,1,null,{"sessionId":"8910528a-9fe5-47a5-8aaa-3b82a9a5b728","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482039 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:24:42 INFO - 1499131482039 Marionette TRACE 378 <- [1,2,null,{}]
18:24:42 INFO - 1499131482040 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:24:42 INFO - 1499131482040 Marionette TRACE 378 <- [1,3,null,{}]
18:24:42 INFO - 1499131482041 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:24:42 INFO - 1499131482042 Marionette TRACE 378 <- [1,4,null,{}]
18:24:42 INFO - 1499131482044 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}]
18:24:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499131482046 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:24:42 INFO - 1499131482047 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:24:42 INFO - 1499131482048 Marionette TRACE 378 <- [1,6,null,{}]
18:24:42 INFO - 1499131482049 Marionette DEBUG Closed connection 378
18:24:42 INFO - 1499131482050 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50046
18:24:42 INFO - 1499131482051 Marionette DEBUG Closed connection 379
18:24:42 INFO - 1499131482052 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50047
18:24:42 INFO - 1499131482053 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:42 INFO - 1499131482053 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482055 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482058 Marionette TRACE 380 <- [1,1,null,{"sessionId":"bba6cbea-26c1-4460-b1c4-abf3f3ce6406","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482059 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:24:42 INFO - 1499131482060 Marionette TRACE 380 <- [1,2,null,{}]
18:24:42 INFO - 1499131482061 Marionette DEBUG Closed connection 380
18:24:42 INFO - 1499131482062 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50048
18:24:42 INFO - 1499131482063 Marionette DEBUG Closed connection 381
18:24:42 INFO - 1499131482063 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50049
18:24:42 INFO - 1499131482064 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:24:42 INFO - 1499131482065 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482067 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482070 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2cad70cd-a700-4661-8b6a-19a4cbc7a1de","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482071 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"}]
18:24:42 INFO - 1499131482072 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"
18:24:42 INFO - 1499131482074 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"
18:24:42 INFO - 1499131482074 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"
18:24:42 INFO - 1499131482089 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"
18:24:42 INFO - 1499131482092 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"
18:24:42 INFO - 1499131482102 Marionette TRACE 382 <- [1,2,null,{}]
18:24:42 INFO - 1499131482103 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:24:42 INFO - 1499131482105 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"be892ee7-3c6c-44f1-8cf8-a358a916a01b","ELEMENT":"be892ee7-3c6c-44f1-8cf8-a358a916a01b"}}]
18:24:42 INFO - 1499131482106 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"be892ee7-3c6c-44f1-8cf8-a358a916a01b"}]
18:24:42 INFO - 1499131482351 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:24:42 INFO - 1499131482352 Marionette TRACE 382 <- [1,4,null,{}]
18:24:42 INFO - 1499131482354 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}]
18:24:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499131482356 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:24:42 INFO - 1499131482357 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:24:42 INFO - 1499131482359 Marionette TRACE 382 <- [1,6,null,{}]
18:24:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 331ms
18:24:42 INFO - TEST-START | test_click.py TestClick.test_input_file
18:24:42 INFO - 1499131482361 Marionette DEBUG Closed connection 382
18:24:42 INFO - 1499131482362 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50050
18:24:42 INFO - 1499131482362 Marionette DEBUG Closed connection 383
18:24:42 INFO - 1499131482363 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50051
18:24:42 INFO - 1499131482364 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:42 INFO - 1499131482365 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482367 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482370 Marionette TRACE 384 <- [1,1,null,{"sessionId":"10eca40b-2a8d-44c6-8dec-a06ff9b8f938","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482371 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:24:42 INFO - 1499131482371 Marionette TRACE 384 <- [1,2,null,{}]
18:24:42 INFO - 1499131482372 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:24:42 INFO - 1499131482372 Marionette TRACE 384 <- [1,3,null,{}]
18:24:42 INFO - 1499131482373 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:24:42 INFO - 1499131482374 Marionette TRACE 384 <- [1,4,null,{}]
18:24:42 INFO - 1499131482375 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}]
18:24:42 INFO - TEST-START: test_click.py TestClick.test_input_file1499131482377 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:24:42 INFO - 1499131482379 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:24:42 INFO - 1499131482380 Marionette TRACE 384 <- [1,6,null,{}]
18:24:42 INFO - 1499131482381 Marionette DEBUG Closed connection 384
18:24:42 INFO - 1499131482382 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50052
18:24:42 INFO - 1499131482383 Marionette DEBUG Closed connection 385
18:24:42 INFO - 1499131482384 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50053
18:24:42 INFO - 1499131482385 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:42 INFO - 1499131482385 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482387 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482390 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b26764a5-08df-4b1b-986c-9a128a2484f4","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482392 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:24:42 INFO - 1499131482393 Marionette TRACE 386 <- [1,2,null,{}]
18:24:42 INFO - 1499131482394 Marionette DEBUG Closed connection 386
18:24:42 INFO - 1499131482395 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50054
18:24:42 INFO - 1499131482396 Marionette DEBUG Closed connection 387
18:24:42 INFO - 1499131482396 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50055
18:24:42 INFO - 1499131482397 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:24:42 INFO - 1499131482398 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482400 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482403 Marionette TRACE 388 <- [1,1,null,{"sessionId":"3fa5bea9-bcd2-41ae-8566-0298ee7e3c4e","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482404 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:24:42 INFO - 1499131482405 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"
18:24:42 INFO - 1499131482407 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"
18:24:42 INFO - 1499131482408 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"
18:24:42 INFO - 1499131482423 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:24:42 INFO - 1499131482428 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:24:42 INFO - 1499131482431 Marionette TRACE 388 <- [1,2,null,{}]
18:24:42 INFO - 1499131482432 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:24:42 INFO - 1499131482434 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"531d4b56-118e-4b26-872c-980bd7d1b832","ELEMENT":"531d4b56-118e-4b26-872c-980bd7d1b832"}}]
18:24:42 INFO - 1499131482435 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"531d4b56-118e-4b26-872c-980bd7d1b832"}]
18:24:42 INFO - 1499131482465 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
18:24:42 INFO - 1499131482467 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}]
18:24:42 INFO - TEST-END: test_click.py TestClick.test_input_file1499131482470 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:24:42 INFO - 1499131482471 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:24:42 INFO - 1499131482472 Marionette TRACE 388 <- [1,6,null,{}]
18:24:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 112ms
18:24:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:24:42 INFO - 1499131482473 Marionette DEBUG Closed connection 388
18:24:42 INFO - 1499131482475 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50056
18:24:42 INFO - 1499131482475 Marionette DEBUG Closed connection 389
18:24:42 INFO - 1499131482476 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50057
18:24:42 INFO - 1499131482477 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:42 INFO - 1499131482478 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482480 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482482 Marionette TRACE 390 <- [1,1,null,{"sessionId":"64ad5945-db92-4029-927c-cc25799547c1","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482483 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:24:42 INFO - 1499131482484 Marionette TRACE 390 <- [1,2,null,{}]
18:24:42 INFO - 1499131482485 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:24:42 INFO - 1499131482485 Marionette TRACE 390 <- [1,3,null,{}]
18:24:42 INFO - 1499131482486 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:24:42 INFO - 1499131482487 Marionette TRACE 390 <- [1,4,null,{}]
18:24:42 INFO - 1499131482488 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}]
18:24:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499131482491 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:24:42 INFO - 1499131482492 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:24:42 INFO - 1499131482493 Marionette TRACE 390 <- [1,6,null,{}]
18:24:42 INFO - 1499131482494 Marionette DEBUG Closed connection 390
18:24:42 INFO - 1499131482495 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50058
18:24:42 INFO - 1499131482496 Marionette DEBUG Closed connection 391
18:24:42 INFO - 1499131482496 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50059
18:24:42 INFO - 1499131482497 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:42 INFO - 1499131482498 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482500 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482502 Marionette TRACE 392 <- [1,1,null,{"sessionId":"54d4772b-c70b-44ec-a4cd-46bb7b9db0a5","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482503 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:24:42 INFO - 1499131482504 Marionette TRACE 392 <- [1,2,null,{}]
18:24:42 INFO - 1499131482506 Marionette DEBUG Closed connection 392
18:24:42 INFO - 1499131482507 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50060
18:24:42 INFO - 1499131482507 Marionette DEBUG Closed connection 393
18:24:42 INFO - 1499131482508 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50061
18:24:42 INFO - 1499131482509 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:24:42 INFO - 1499131482510 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482511 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482514 Marionette TRACE 394 <- [1,1,null,{"sessionId":"959b1903-7477-436c-aeed-dc97ee99bd48","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482515 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"}]
18:24:42 INFO - 1499131482516 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:24:42 INFO - 1499131482518 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:24:42 INFO - 1499131482519 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:24:42 INFO - 1499131482535 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"
18:24:42 INFO - 1499131482538 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"
18:24:42 INFO - 1499131482540 Marionette TRACE 394 <- [1,2,null,{}]
18:24:42 INFO - 1499131482547 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:24:42 INFO - 1499131482550 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b31b743-a91b-42ea-9c46-eea274a64ea5","ELEMENT":"0b31b743-a91b-42ea-9c46-eea274a64ea5"}}]
18:24:42 INFO - 1499131482551 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:24:42 INFO - 1499131482553 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4e281340-4939-455a-8b87-ef360844a979","ELEMENT":"4e281340-4939-455a-8b87-ef360844a979"}}]
18:24:42 INFO - 1499131482554 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"0b31b743-a91b-42ea-9c46-eea274a64ea5"}]
18:24:42 INFO - 1499131482794 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:24:42 INFO - 1499131482794 Marionette TRACE 394 <- [1,5,null,{}]
18:24:42 INFO - 1499131482796 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4e281340-4939-455a-8b87-ef360844a979"}]
18:24:42 INFO - 1499131482810 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
18:24:42 INFO - 1499131482812 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:24:42 INFO - 1499131482814 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:24:42 INFO - 1499131482816 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}]
18:24:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499131482818 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:24:42 INFO - 1499131482819 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:24:42 INFO - 1499131482820 Marionette TRACE 394 <- [1,9,null,{}]
18:24:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 348ms
18:24:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:24:42 INFO - 1499131482822 Marionette DEBUG Closed connection 394
18:24:42 INFO - 1499131482823 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50062
18:24:42 INFO - 1499131482824 Marionette DEBUG Closed connection 395
18:24:42 INFO - 1499131482824 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50063
18:24:42 INFO - 1499131482825 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:42 INFO - 1499131482826 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482828 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482831 Marionette TRACE 396 <- [1,1,null,{"sessionId":"18687b38-0c4f-48de-80ce-da4dcde58cba","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482832 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:24:42 INFO - 1499131482832 Marionette TRACE 396 <- [1,2,null,{}]
18:24:42 INFO - 1499131482833 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:24:42 INFO - 1499131482833 Marionette TRACE 396 <- [1,3,null,{}]
18:24:42 INFO - 1499131482834 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:24:42 INFO - 1499131482834 Marionette TRACE 396 <- [1,4,null,{}]
18:24:42 INFO - 1499131482836 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}]
18:24:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499131482838 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:24:42 INFO - 1499131482839 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:24:42 INFO - 1499131482841 Marionette TRACE 396 <- [1,6,null,{}]
18:24:42 INFO - 1499131482842 Marionette DEBUG Closed connection 396
18:24:42 INFO - 1499131482843 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50064
18:24:42 INFO - 1499131482844 Marionette DEBUG Closed connection 397
18:24:42 INFO - 1499131482844 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50065
18:24:42 INFO - 1499131482845 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:24:42 INFO - 1499131482846 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482848 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482850 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a8dad259-3f94-427e-81fc-2eaa4c324163","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482851 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:24:42 INFO - 1499131482852 Marionette TRACE 398 <- [1,2,null,{}]
18:24:42 INFO - 1499131482854 Marionette DEBUG Closed connection 398
18:24:42 INFO - 1499131482855 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50066
18:24:42 INFO - 1499131482855 Marionette DEBUG Closed connection 399
18:24:42 INFO - 1499131482856 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50067
18:24:42 INFO - 1499131482857 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:24:42 INFO - 1499131482857 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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}
18:24:42 INFO - 1499131482859 Marionette DEBUG Register listener.js for window 12
18:24:42 INFO - 1499131482862 Marionette TRACE 400 <- [1,1,null,{"sessionId":"f4d87a78-6349-4287-944a-04fb2f5db8cd","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":3824,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpbjim61.mozrunner","moz:accessibilityChecks":false}}]
18:24:42 INFO - 1499131482863 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"}]
18:24:42 INFO - 1499131482864 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"
18:24:42 INFO - 1499131482866 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"
18:24:42 INFO - 1499131482866 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"
18:24:42 INFO - 1499131482884 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"
18:24:42 INFO - 1499131482885 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"
18:24:42 INFO - 1499131482888 Marionette TRACE 400 <- [1,2,null,{}]
18:24:42 INFO - 1499131482889 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:24:42 INFO - 1499131482896 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"50f88ace-261b-4a72-97d0-19a4aebf0542","ELEMENT":"50f88ace-261b-4a72-97d0-19a4aebf0542"}}]
18:24:42 INFO - 1499131482897 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"50f88ace-261b-4a72-97d0-19a4aebf0542"}]
18:24:42 INFO - 1499131482917 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:24:42 INFO - 1499131482919 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"50f88ace-261b-4a72-97d0-19a4aebf0542"}]
18:24:42 INFO - 1499131482933 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element