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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
12:47:45 INFO - 1499716065913 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}]
12:47:45 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499716065915 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:47:45 INFO - 1499716065916 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:47:45 INFO - 1499716065917 Marionette TRACE 352 <- [1,6,null,{}]
12:47:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 126ms
12:47:45 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:47:45 INFO - 1499716065919 Marionette DEBUG Closed connection 352
12:47:45 INFO - 1499716065920 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49814
12:47:45 INFO - 1499716065921 Marionette DEBUG Closed connection 353
12:47:45 INFO - 1499716065922 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49815
12:47:45 INFO - 1499716065923 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:45 INFO - 1499716065923 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:45 INFO - 1499716065925 Marionette DEBUG Register listener.js for window 12
12:47:45 INFO - 1499716065935 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1a1a732a-456d-476c-82f1-7c8dfd7305c4","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:45 INFO - 1499716065937 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:47:45 INFO - 1499716065937 Marionette TRACE 354 <- [1,2,null,{}]
12:47:45 INFO - 1499716065938 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:45 INFO - 1499716065938 Marionette TRACE 354 <- [1,3,null,{}]
12:47:45 INFO - 1499716065939 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:45 INFO - 1499716065940 Marionette TRACE 354 <- [1,4,null,{}]
12:47:45 INFO - 1499716065941 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}]
12:47:45 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499716065944 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:47:45 INFO - 1499716065945 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:47:45 INFO - 1499716065946 Marionette TRACE 354 <- [1,6,null,{}]
12:47:45 INFO - 1499716065948 Marionette DEBUG Closed connection 354
12:47:45 INFO - 1499716065948 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49816
12:47:45 INFO - 1499716065949 Marionette DEBUG Closed connection 355
12:47:45 INFO - 1499716065950 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49817
12:47:45 INFO - 1499716065951 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:45 INFO - 1499716065951 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:45 INFO - 1499716065953 Marionette DEBUG Register listener.js for window 12
12:47:45 INFO - 1499716065956 Marionette TRACE 356 <- [1,1,null,{"sessionId":"99e9d3f2-7af6-4fac-8f8b-631dc20f1d1f","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:45 INFO - 1499716065957 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:47:45 INFO - 1499716065958 Marionette TRACE 356 <- [1,2,null,{}]
12:47:45 INFO - 1499716065960 Marionette DEBUG Closed connection 356
12:47:45 INFO - 1499716065961 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49818
12:47:45 INFO - 1499716065961 Marionette DEBUG Closed connection 357
12:47:45 INFO - 1499716065962 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49819
12:47:45 INFO - 1499716065963 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:45 INFO - 1499716065963 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:45 INFO - 1499716065965 Marionette DEBUG Register listener.js for window 12
12:47:45 INFO - 1499716065968 Marionette TRACE 358 <- [1,1,null,{"sessionId":"771b4b74-8b3f-4b57-b472-e4a60cf7afab","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:45 INFO - 1499716065970 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49379/clicks.html"}]
12:47:45 INFO - 1499716065971 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49379/hidden.html"
12:47:45 INFO - 1499716065976 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49379/hidden.html"
12:47:45 INFO - 1499716065977 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49379/hidden.html"
12:47:46 INFO - 1499716065996 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49379/clicks.html"
12:47:46 INFO - 1499716065997 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49379/clicks.html"
12:47:46 INFO - 1499716066001 Marionette TRACE 358 <- [1,2,null,{}]
12:47:46 INFO - 1499716066002 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:47:46 INFO - 1499716066011 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"044829f8-9339-45d2-9579-8d3cb0f37df1","ELEMENT":"044829f8-9339-45d2-9579-8d3cb0f37df1"}}]
12:47:46 INFO - 1499716066012 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"044829f8-9339-45d2-9579-8d3cb0f37df1"}]
12:47:46 INFO - 1499716066051 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49379/clicks.html"
12:47:46 INFO - 1499716066059 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49379/clicks.html"
12:47:46 INFO - 1499716066059 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49379/clicks.html"
12:47:46 INFO - 1499716066077 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49379/test.html"
12:47:46 INFO - 1499716066078 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49379/test.html"
12:47:46 INFO - 1499716066081 Marionette TRACE 358 <- [1,4,null,{}]
12:47:46 INFO - 1499716066082 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:47:46 INFO - 1499716066086 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"adcdd0a8-9450-42fb-a4c1-6faa9c25d3fc","ELEMENT":"adcdd0a8-9450-42fb-a4c1-6faa9c25d3fc"}}]
12:47:46 INFO - 1499716066093 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:47:46 INFO - 1499716066094 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:47:46 INFO - 1499716066096 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}]
12:47:46 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499716066098 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:47:46 INFO - 1499716066099 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:47:46 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 183ms
12:47:46 INFO - TEST-START | test_click.py TestClick.test_container_element
12:47:46 INFO - 1499716066100 Marionette TRACE 358 <- [1,8,null,{}]
12:47:46 INFO - 1499716066102 Marionette DEBUG Closed connection 358
12:47:46 INFO - 1499716066103 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49822
12:47:46 INFO - 1499716066104 Marionette DEBUG Closed connection 359
12:47:46 INFO - 1499716066105 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49823
12:47:46 INFO - 1499716066107 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:46 INFO - 1499716066108 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066110 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066113 Marionette TRACE 360 <- [1,1,null,{"sessionId":"cfa1550f-a422-46a8-9574-9b5c75cb7a84","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066114 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:47:46 INFO - 1499716066115 Marionette TRACE 360 <- [1,2,null,{}]
12:47:46 INFO - 1499716066116 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:46 INFO - 1499716066116 Marionette TRACE 360 <- [1,3,null,{}]
12:47:46 INFO - 1499716066117 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:46 INFO - 1499716066117 Marionette TRACE 360 <- [1,4,null,{}]
12:47:46 INFO - 1499716066119 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}]
12:47:46 INFO - TEST-START: test_click.py TestClick.test_container_element1499716066122 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:47:46 INFO - 1499716066122 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:47:46 INFO - 1499716066124 Marionette TRACE 360 <- [1,6,null,{}]
12:47:46 INFO - 1499716066125 Marionette DEBUG Closed connection 360
12:47:46 INFO - 1499716066126 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49824
12:47:46 INFO - 1499716066127 Marionette DEBUG Closed connection 361
12:47:46 INFO - 1499716066127 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49825
12:47:46 INFO - 1499716066128 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:46 INFO - 1499716066129 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066131 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066133 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c8403542-600c-414c-a19f-a3b4339901ee","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066135 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:47:46 INFO - 1499716066136 Marionette TRACE 362 <- [1,2,null,{}]
12:47:46 INFO - 1499716066138 Marionette DEBUG Closed connection 362
12:47:46 INFO - 1499716066139 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49826
12:47:46 INFO - 1499716066139 Marionette DEBUG Closed connection 363
12:47:46 INFO - 1499716066140 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49827
12:47:46 INFO - 1499716066141 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:46 INFO - 1499716066142 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066143 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066146 Marionette TRACE 364 <- [1,1,null,{"sessionId":"ecb89e99-52e7-4ed3-ab81-2c6a31762e1d","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066148 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"}]
12:47:46 INFO - 1499716066149 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49379/test.html"
12:47:46 INFO - 1499716066152 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49379/test.html"
12:47:46 INFO - 1499716066153 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49379/test.html"
12:47:46 INFO - 1499716066181 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"
12:47:46 INFO - 1499716066187 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"
12:47:46 INFO - 1499716066197 Marionette TRACE 364 <- [1,2,null,{}]
12:47:46 INFO - 1499716066199 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:47:46 INFO - 1499716066201 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"408b7416-48b4-4b04-b4c2-3942031584ef","ELEMENT":"408b7416-48b4-4b04-b4c2-3942031584ef"}}]
12:47:46 INFO - 1499716066202 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"408b7416-48b4-4b04-b4c2-3942031584ef"}]
12:47:46 INFO - 1499716066439 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:46 INFO - 1499716066440 Marionette TRACE 364 <- [1,4,null,{}]
12:47:46 INFO - 1499716066441 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"408b7416-48b4-4b04-b4c2-3942031584ef","name":"selected"}]
12:47:46 INFO - 1499716066445 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:47:46 INFO - 1499716066447 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}]
12:47:46 INFO - TEST-END: test_click.py TestClick.test_container_element1499716066449 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:47:46 INFO - 1499716066450 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:47:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 352ms
12:47:46 INFO - 1499716066452 Marionette TRACE 364 <- [1,7,null,{}]
12:47:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:47:46 INFO - 1499716066455 Marionette DEBUG Closed connection 364
12:47:46 INFO - 1499716066456 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49828
12:47:46 INFO - 1499716066457 Marionette DEBUG Closed connection 365
12:47:46 INFO - 1499716066457 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49829
12:47:46 INFO - 1499716066458 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:46 INFO - 1499716066459 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066461 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066470 Marionette TRACE 366 <- [1,1,null,{"sessionId":"c96c170d-10ca-4b5e-a779-dc045a6acc1b","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066472 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:47:46 INFO - 1499716066472 Marionette TRACE 366 <- [1,2,null,{}]
12:47:46 INFO - 1499716066473 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:46 INFO - 1499716066474 Marionette TRACE 366 <- [1,3,null,{}]
12:47:46 INFO - 1499716066474 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:46 INFO - 1499716066475 Marionette TRACE 366 <- [1,4,null,{}]
12:47:46 INFO - 1499716066476 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}]
12:47:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499716066479 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:47:46 INFO - 1499716066480 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:47:46 INFO - 1499716066481 Marionette TRACE 366 <- [1,6,null,{}]
12:47:46 INFO - 1499716066483 Marionette DEBUG Closed connection 366
12:47:46 INFO - 1499716066483 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49830
12:47:46 INFO - 1499716066484 Marionette DEBUG Closed connection 367
12:47:46 INFO - 1499716066485 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49831
12:47:46 INFO - 1499716066486 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:46 INFO - 1499716066486 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066488 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066492 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f52eeb3f-3c5f-4bca-89fc-c5fad049df9f","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066494 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:47:46 INFO - 1499716066495 Marionette TRACE 368 <- [1,2,null,{}]
12:47:46 INFO - 1499716066497 Marionette DEBUG Closed connection 368
12:47:46 INFO - 1499716066498 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49832
12:47:46 INFO - 1499716066498 Marionette DEBUG Closed connection 369
12:47:46 INFO - 1499716066499 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49833
12:47:46 INFO - 1499716066500 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:46 INFO - 1499716066501 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066502 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066505 Marionette TRACE 370 <- [1,1,null,{"sessionId":"93af7566-e35c-427c-abce-2af7cdd9c924","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066507 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"}]
12:47:46 INFO - 1499716066508 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"
12:47:46 INFO - 1499716066510 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"
12:47:46 INFO - 1499716066511 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"
12:47:46 INFO - 1499716066528 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"
12:47:46 INFO - 1499716066529 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"
12:47:46 INFO - 1499716066532 Marionette TRACE 370 <- [1,2,null,{}]
12:47:46 INFO - 1499716066533 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:47:46 INFO - 1499716066536 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e0d0ba0-5080-41ba-8561-dc908f9574c3","ELEMENT":"9e0d0ba0-5080-41ba-8561-dc908f9574c3"}}]
12:47:46 INFO - 1499716066536 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9e0d0ba0-5080-41ba-8561-dc908f9574c3"}]
12:47:46 INFO - 1499716066780 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:46 INFO - 1499716066781 Marionette TRACE 370 <- [1,4,null,{}]
12:47:46 INFO - 1499716066782 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9e0d0ba0-5080-41ba-8561-dc908f9574c3","name":"selected"}]
12:47:46 INFO - 1499716066786 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:47:46 INFO - 1499716066788 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}]
12:47:46 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499716066790 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:47:46 INFO - 1499716066791 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:47:46 INFO - 1499716066792 Marionette TRACE 370 <- [1,7,null,{}]
12:47:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 339ms
12:47:46 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:47:46 INFO - 1499716066794 Marionette DEBUG Closed connection 370
12:47:46 INFO - 1499716066795 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49834
12:47:46 INFO - 1499716066796 Marionette DEBUG Closed connection 371
12:47:46 INFO - 1499716066796 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49835
12:47:46 INFO - 1499716066797 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:46 INFO - 1499716066798 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066800 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066802 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8e87ddd0-0d67-419b-8c82-47a83886ff08","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066804 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:47:46 INFO - 1499716066805 Marionette TRACE 372 <- [1,2,null,{}]
12:47:46 INFO - 1499716066806 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:46 INFO - 1499716066806 Marionette TRACE 372 <- [1,3,null,{}]
12:47:46 INFO - 1499716066807 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:46 INFO - 1499716066807 Marionette TRACE 372 <- [1,4,null,{}]
12:47:46 INFO - 1499716066809 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}]
12:47:46 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499716066811 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:47:46 INFO - 1499716066812 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:47:46 INFO - 1499716066813 Marionette TRACE 372 <- [1,6,null,{}]
12:47:46 INFO - 1499716066815 Marionette DEBUG Closed connection 372
12:47:46 INFO - 1499716066816 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49836
12:47:46 INFO - 1499716066816 Marionette DEBUG Closed connection 373
12:47:46 INFO - 1499716066817 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49837
12:47:46 INFO - 1499716066818 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:46 INFO - 1499716066818 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066820 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066823 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2da06c0c-60ba-4862-a468-1f772f943699","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066825 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:47:46 INFO - 1499716066826 Marionette TRACE 374 <- [1,2,null,{}]
12:47:46 INFO - 1499716066827 Marionette DEBUG Closed connection 374
12:47:46 INFO - 1499716066828 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49838
12:47:46 INFO - 1499716066829 Marionette DEBUG Closed connection 375
12:47:46 INFO - 1499716066830 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49839
12:47:46 INFO - 1499716066830 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:46 INFO - 1499716066831 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:46 INFO - 1499716066833 Marionette DEBUG Register listener.js for window 12
12:47:46 INFO - 1499716066835 Marionette TRACE 376 <- [1,1,null,{"sessionId":"3b16225f-26f8-495e-a993-52d80be5d6fc","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:46 INFO - 1499716066837 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"}]
12:47:46 INFO - 1499716066839 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"
12:47:46 INFO - 1499716066841 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"
12:47:46 INFO - 1499716066842 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"
12:47:46 INFO - 1499716066852 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"
12:47:46 INFO - 1499716066856 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"
12:47:46 INFO - 1499716066865 Marionette TRACE 376 <- [1,2,null,{}]
12:47:46 INFO - 1499716066868 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:47:46 INFO - 1499716066870 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8289d5f3-12e5-4699-b7c0-21c4a77b0bf0","ELEMENT":"8289d5f3-12e5-4699-b7c0-21c4a77b0bf0"}}]
12:47:46 INFO - 1499716066871 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8289d5f3-12e5-4699-b7c0-21c4a77b0bf0"}]
12:47:47 INFO - 1499716067105 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:47 INFO - 1499716067106 Marionette TRACE 376 <- [1,4,null,{}]
12:47:47 INFO - 1499716067108 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}]
12:47:47 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499716067111 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499716067112 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499716067113 Marionette TRACE 376 <- [1,6,null,{}]
12:47:47 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 320ms
12:47:47 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:47:47 INFO - 1499716067115 Marionette DEBUG Closed connection 376
12:47:47 INFO - 1499716067116 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49842
12:47:47 INFO - 1499716067116 Marionette DEBUG Closed connection 377
12:47:47 INFO - 1499716067117 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49843
12:47:47 INFO - 1499716067118 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499716067119 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067121 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067123 Marionette TRACE 378 <- [1,1,null,{"sessionId":"66cc55da-c05a-4012-87dd-38befe24874e","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067125 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:47:47 INFO - 1499716067126 Marionette TRACE 378 <- [1,2,null,{}]
12:47:47 INFO - 1499716067126 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:47 INFO - 1499716067127 Marionette TRACE 378 <- [1,3,null,{}]
12:47:47 INFO - 1499716067128 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:47 INFO - 1499716067128 Marionette TRACE 378 <- [1,4,null,{}]
12:47:47 INFO - 1499716067130 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}]
12:47:47 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499716067132 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499716067133 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499716067134 Marionette TRACE 378 <- [1,6,null,{}]
12:47:47 INFO - 1499716067135 Marionette DEBUG Closed connection 378
12:47:47 INFO - 1499716067136 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49844
12:47:47 INFO - 1499716067137 Marionette DEBUG Closed connection 379
12:47:47 INFO - 1499716067137 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49845
12:47:47 INFO - 1499716067138 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499716067139 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067141 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067150 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8ec98a55-294e-44f0-a068-2c280c7ba5db","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067152 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:47:47 INFO - 1499716067153 Marionette TRACE 380 <- [1,2,null,{}]
12:47:47 INFO - 1499716067155 Marionette DEBUG Closed connection 380
12:47:47 INFO - 1499716067155 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49846
12:47:47 INFO - 1499716067156 Marionette DEBUG Closed connection 381
12:47:47 INFO - 1499716067157 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49847
12:47:47 INFO - 1499716067158 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:47 INFO - 1499716067158 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067160 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067163 Marionette TRACE 382 <- [1,1,null,{"sessionId":"1c361236-8565-40bd-8212-37ba6040c621","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067164 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"}]
12:47:47 INFO - 1499716067166 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"
12:47:47 INFO - 1499716067168 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"
12:47:47 INFO - 1499716067168 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"
12:47:47 INFO - 1499716067182 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"
12:47:47 INFO - 1499716067186 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"
12:47:47 INFO - 1499716067188 Marionette TRACE 382 <- [1,2,null,{}]
12:47:47 INFO - 1499716067195 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:47:47 INFO - 1499716067198 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ac1a71a3-38fe-4782-ade3-1d0c0e5d2c6e","ELEMENT":"ac1a71a3-38fe-4782-ade3-1d0c0e5d2c6e"}}]
12:47:47 INFO - 1499716067199 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ac1a71a3-38fe-4782-ade3-1d0c0e5d2c6e"}]
12:47:47 INFO - 1499716067438 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:47 INFO - 1499716067439 Marionette TRACE 382 <- [1,4,null,{}]
12:47:47 INFO - 1499716067441 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}]
12:47:47 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499716067443 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499716067444 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499716067446 Marionette TRACE 382 <- [1,6,null,{}]
12:47:47 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 333ms
12:47:47 INFO - TEST-START | test_click.py TestClick.test_input_file
12:47:47 INFO - 1499716067448 Marionette DEBUG Closed connection 382
12:47:47 INFO - 1499716067449 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49848
12:47:47 INFO - 1499716067449 Marionette DEBUG Closed connection 383
12:47:47 INFO - 1499716067450 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49849
12:47:47 INFO - 1499716067451 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499716067452 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067454 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067456 Marionette TRACE 384 <- [1,1,null,{"sessionId":"be1f4306-8715-4d27-b50f-7cf38687e8eb","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067458 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:47:47 INFO - 1499716067459 Marionette TRACE 384 <- [1,2,null,{}]
12:47:47 INFO - 1499716067459 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:47 INFO - 1499716067460 Marionette TRACE 384 <- [1,3,null,{}]
12:47:47 INFO - 1499716067461 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:47 INFO - 1499716067461 Marionette TRACE 384 <- [1,4,null,{}]
12:47:47 INFO - 1499716067463 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}]
12:47:47 INFO - TEST-START: test_click.py TestClick.test_input_file1499716067465 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499716067466 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499716067467 Marionette TRACE 384 <- [1,6,null,{}]
12:47:47 INFO - 1499716067469 Marionette DEBUG Closed connection 384
12:47:47 INFO - 1499716067469 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49850
12:47:47 INFO - 1499716067470 Marionette DEBUG Closed connection 385
12:47:47 INFO - 1499716067471 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49851
12:47:47 INFO - 1499716067472 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499716067472 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067474 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067476 Marionette TRACE 386 <- [1,1,null,{"sessionId":"efe606e2-8d71-4d62-a042-338eea415f2a","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067478 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:47:47 INFO - 1499716067479 Marionette TRACE 386 <- [1,2,null,{}]
12:47:47 INFO - 1499716067481 Marionette DEBUG Closed connection 386
12:47:47 INFO - 1499716067481 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49852
12:47:47 INFO - 1499716067482 Marionette DEBUG Closed connection 387
12:47:47 INFO - 1499716067483 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49853
12:47:47 INFO - 1499716067484 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:47 INFO - 1499716067484 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067486 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067489 Marionette TRACE 388 <- [1,1,null,{"sessionId":"0f67644e-3bbc-41fe-bac5-461547a4335f","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067491 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:47:47 INFO - 1499716067493 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"
12:47:47 INFO - 1499716067496 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"
12:47:47 INFO - 1499716067497 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"
12:47:47 INFO - 1499716067512 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:47 INFO - 1499716067515 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:47 INFO - 1499716067518 Marionette TRACE 388 <- [1,2,null,{}]
12:47:47 INFO - 1499716067519 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:47:47 INFO - 1499716067528 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa7fb104-63bc-4f81-8780-2d618e562e1f","ELEMENT":"aa7fb104-63bc-4f81-8780-2d618e562e1f"}}]
12:47:47 INFO - 1499716067529 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"aa7fb104-63bc-4f81-8780-2d618e562e1f"}]
12:47:47 INFO - 1499716067554 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
12:47:47 INFO - 1499716067556 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}]
12:47:47 INFO - TEST-END: test_click.py TestClick.test_input_file1499716067558 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499716067559 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499716067560 Marionette TRACE 388 <- [1,6,null,{}]
12:47:47 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 115ms
12:47:47 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:47:47 INFO - 1499716067562 Marionette DEBUG Closed connection 388
12:47:47 INFO - 1499716067564 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49854
12:47:47 INFO - 1499716067565 Marionette DEBUG Closed connection 389
12:47:47 INFO - 1499716067566 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49855
12:47:47 INFO - 1499716067567 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499716067567 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067569 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067572 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d5ccdd1b-8c60-4e42-b728-62bfc146f7c3","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067574 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:47:47 INFO - 1499716067574 Marionette TRACE 390 <- [1,2,null,{}]
12:47:47 INFO - 1499716067575 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:47 INFO - 1499716067575 Marionette TRACE 390 <- [1,3,null,{}]
12:47:47 INFO - 1499716067576 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:47 INFO - 1499716067577 Marionette TRACE 390 <- [1,4,null,{}]
12:47:47 INFO - 1499716067578 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}]
12:47:47 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499716067580 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499716067581 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499716067583 Marionette TRACE 390 <- [1,6,null,{}]
12:47:47 INFO - 1499716067584 Marionette DEBUG Closed connection 390
12:47:47 INFO - 1499716067585 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49856
12:47:47 INFO - 1499716067586 Marionette DEBUG Closed connection 391
12:47:47 INFO - 1499716067587 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49857
12:47:47 INFO - 1499716067588 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499716067588 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067590 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067592 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d8f9651a-69bb-411b-b816-1cab573d9d77","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067601 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:47:47 INFO - 1499716067602 Marionette TRACE 392 <- [1,2,null,{}]
12:47:47 INFO - 1499716067604 Marionette DEBUG Closed connection 392
12:47:47 INFO - 1499716067604 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49858
12:47:47 INFO - 1499716067605 Marionette DEBUG Closed connection 393
12:47:47 INFO - 1499716067606 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49859
12:47:47 INFO - 1499716067606 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:47 INFO - 1499716067607 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067609 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067612 Marionette TRACE 394 <- [1,1,null,{"sessionId":"122238da-8ade-499c-936f-f4e06a70fc00","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067614 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"}]
12:47:47 INFO - 1499716067615 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:47 INFO - 1499716067617 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:47 INFO - 1499716067618 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:47 INFO - 1499716067634 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"
12:47:47 INFO - 1499716067637 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"
12:47:47 INFO - 1499716067645 Marionette TRACE 394 <- [1,2,null,{}]
12:47:47 INFO - 1499716067647 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:47:47 INFO - 1499716067649 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7612ed6f-c5be-470a-a530-b2fa6f2c4130","ELEMENT":"7612ed6f-c5be-470a-a530-b2fa6f2c4130"}}]
12:47:47 INFO - 1499716067649 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:47:47 INFO - 1499716067651 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"929a1425-4abb-409a-99aa-9fd3dac4c2ee","ELEMENT":"929a1425-4abb-409a-99aa-9fd3dac4c2ee"}}]
12:47:47 INFO - 1499716067652 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7612ed6f-c5be-470a-a530-b2fa6f2c4130"}]
12:47:47 INFO - 1499716067885 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:47 INFO - 1499716067886 Marionette TRACE 394 <- [1,5,null,{}]
12:47:47 INFO - 1499716067887 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"929a1425-4abb-409a-99aa-9fd3dac4c2ee"}]
12:47:47 INFO - 1499716067901 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
12:47:47 INFO - 1499716067902 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:47:47 INFO - 1499716067905 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:47:47 INFO - 1499716067906 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}]
12:47:47 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499716067909 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:47:47 INFO - 1499716067909 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:47:47 INFO - 1499716067911 Marionette TRACE 394 <- [1,9,null,{}]
12:47:47 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 349ms
12:47:47 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:47:47 INFO - 1499716067912 Marionette DEBUG Closed connection 394
12:47:47 INFO - 1499716067914 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49860
12:47:47 INFO - 1499716067914 Marionette DEBUG Closed connection 395
12:47:47 INFO - 1499716067915 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49861
12:47:47 INFO - 1499716067916 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499716067917 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067919 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067922 Marionette TRACE 396 <- [1,1,null,{"sessionId":"74e6f5ec-59f1-4b86-abed-05da6e757420","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067923 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:47:47 INFO - 1499716067924 Marionette TRACE 396 <- [1,2,null,{}]
12:47:47 INFO - 1499716067925 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:47 INFO - 1499716067925 Marionette TRACE 396 <- [1,3,null,{}]
12:47:47 INFO - 1499716067926 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:47 INFO - 1499716067926 Marionette TRACE 396 <- [1,4,null,{}]
12:47:47 INFO - 1499716067928 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}]
12:47:47 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499716067930 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499716067931 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499716067932 Marionette TRACE 396 <- [1,6,null,{}]
12:47:47 INFO - 1499716067934 Marionette DEBUG Closed connection 396
12:47:47 INFO - 1499716067935 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49862
12:47:47 INFO - 1499716067935 Marionette DEBUG Closed connection 397
12:47:47 INFO - 1499716067936 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49863
12:47:47 INFO - 1499716067937 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499716067937 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067939 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067942 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5f8bceb0-05dd-4fe4-ad61-9161dbce5d08","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067944 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:47:47 INFO - 1499716067945 Marionette TRACE 398 <- [1,2,null,{}]
12:47:47 INFO - 1499716067947 Marionette DEBUG Closed connection 398
12:47:47 INFO - 1499716067947 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49864
12:47:47 INFO - 1499716067948 Marionette DEBUG Closed connection 399
12:47:47 INFO - 1499716067949 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49865
12:47:47 INFO - 1499716067950 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:47 INFO - 1499716067950 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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499716067952 Marionette DEBUG Register listener.js for window 12
12:47:47 INFO - 1499716067954 Marionette TRACE 400 <- [1,1,null,{"sessionId":"da190b32-fbf4-421e-8c4f-1b5713ff468a","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":3860,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnb5r8e.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499716067956 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"}]
12:47:47 INFO - 1499716067958 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"
12:47:47 INFO - 1499716067960 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"
12:47:47 INFO - 1499716067960 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"
12:47:47 INFO - 1499716067977 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"
12:47:47 INFO - 1499716067979 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"
12:47:47 INFO - 1499716067981 Marionette TRACE 400 <- [1,2,null,{}]
12:47:47 INFO - 1499716067982 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:47:47 INFO - 1499716067984 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f904118-4a6a-43ce-87bf-ae5f74f1a0ed","ELEMENT":"7f904118-4a6a-43ce-87bf-ae5f74f1a0ed"}}]
12:47:47 INFO - 1499716067985 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"7f904118-4a6a-43ce-87bf-ae5f74f1a0ed"}]
12:47:48 INFO - 1499716068011 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:47:48 INFO - 1499716068013 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"7f904118-4a6a-43ce-87bf-ae5f74f1a0ed"}]
12:47:48 INFO - 1499716068030 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element