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]
06:26:31 INFO - 1499606791973 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}]
06:26:31 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499606791975 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:26:31 INFO - 1499606791976 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:26:31 INFO - 1499606791977 Marionette TRACE 352 <- [1,6,null,{}]
06:26:31 INFO - 1499606791979 Marionette DEBUG Closed connection 352
06:26:31 INFO - 1499606791979 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49791
06:26:31 INFO - 1499606791980 Marionette DEBUG Closed connection 353
06:26:31 INFO - 1499606791981 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49792
06:26:31 INFO - 1499606791982 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:31 INFO - 1499606791982 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:31 INFO - 1499606791984 Marionette DEBUG Register listener.js for window 4294967297
06:26:31 INFO - 1499606791987 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ae9db83c-f8e5-4da2-b992-19ea3b763f6a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:31 INFO - 1499606791988 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:26:31 INFO - 1499606791989 Marionette TRACE 354 <- [1,2,null,{}]
06:26:31 INFO - 1499606791990 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:31 INFO - 1499606791990 Marionette TRACE 354 <- [1,3,null,{}]
06:26:31 INFO - 1499606791991 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:32 INFO - 1499606791991 Marionette TRACE 354 <- [1,4,null,{}]
06:26:32 INFO - 1499606791993 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}]
06:26:32 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499606791995 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:26:32 INFO - 1499606791996 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:26:32 INFO - 1499606791997 Marionette TRACE 354 <- [1,6,null,{}]
06:26:32 INFO - 1499606791998 Marionette DEBUG Closed connection 354
06:26:32 INFO - 1499606791999 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49793
06:26:32 INFO - 1499606792000 Marionette DEBUG Closed connection 355
06:26:32 INFO - 1499606792000 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49794
06:26:32 INFO - 1499606792001 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:32 INFO - 1499606792002 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792004 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792006 Marionette TRACE 356 <- [1,1,null,{"sessionId":"b7dd2751-118c-4dcb-b009-a495c74940e1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792007 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:26:32 INFO - 1499606792008 Marionette TRACE 356 <- [1,2,null,{}]
06:26:32 INFO - 1499606792010 Marionette DEBUG Closed connection 356
06:26:32 INFO - 1499606792010 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49795
06:26:32 INFO - 1499606792011 Marionette DEBUG Closed connection 357
06:26:32 INFO - 1499606792012 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49796
06:26:32 INFO - 1499606792012 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:32 INFO - 1499606792013 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792015 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792024 Marionette TRACE 358 <- [1,1,null,{"sessionId":"bc2c7a6a-97d3-4918-b774-f19f22eaa302","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792026 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49357/clicks.html"}]
06:26:32 INFO - 1499606792028 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49357/hidden.html"
06:26:32 INFO - 1499606792033 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49357/hidden.html"
06:26:32 INFO - 1499606792034 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49357/hidden.html"
06:26:32 INFO - 1499606792039 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49357/clicks.html"
06:26:32 INFO - 1499606792044 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49357/clicks.html"
06:26:32 INFO - 1499606792048 Marionette TRACE 358 <- [1,2,null,{}]
06:26:32 INFO - 1499606792051 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:26:32 INFO - 1499606792053 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1d263730-e4e1-428b-9b32-01aa8a7cda20","ELEMENT":"1d263730-e4e1-428b-9b32-01aa8a7cda20"}}]
06:26:32 INFO - 1499606792054 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"1d263730-e4e1-428b-9b32-01aa8a7cda20"}]
06:26:32 INFO - 1499606792092 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49357/clicks.html"
06:26:32 INFO - 1499606792100 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49357/clicks.html"
06:26:32 INFO - 1499606792100 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49357/clicks.html"
06:26:32 INFO - 1499606792107 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49357/test.html"
06:26:32 INFO - 1499606792112 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49357/test.html"
06:26:32 INFO - 1499606792120 Marionette TRACE 358 <- [1,4,null,{}]
06:26:32 INFO - 1499606792125 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:26:32 INFO - 1499606792130 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"388ec786-93ab-4112-bc3e-12d08428ec2f","ELEMENT":"388ec786-93ab-4112-bc3e-12d08428ec2f"}}]
06:26:32 INFO - 1499606792132 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:26:32 INFO - 1499606792132 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:26:32 INFO - 1499606792134 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}]
06:26:32 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 160ms
06:26:32 INFO - TEST-START | test_click.py TestClick.test_container_element
06:26:32 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499606792136 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:26:32 INFO - 1499606792137 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:26:32 INFO - 1499606792138 Marionette TRACE 358 <- [1,8,null,{}]
06:26:32 INFO - 1499606792139 Marionette DEBUG Closed connection 358
06:26:32 INFO - 1499606792140 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49799
06:26:32 INFO - 1499606792141 Marionette DEBUG Closed connection 359
06:26:32 INFO - 1499606792141 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49800
06:26:32 INFO - 1499606792142 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:32 INFO - 1499606792143 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792145 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792148 Marionette TRACE 360 <- [1,1,null,{"sessionId":"6d7b2f08-5d9b-4d82-aebb-dfc28d7a83d0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792149 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:26:32 INFO - 1499606792149 Marionette TRACE 360 <- [1,2,null,{}]
06:26:32 INFO - 1499606792150 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:32 INFO - 1499606792150 Marionette TRACE 360 <- [1,3,null,{}]
06:26:32 INFO - 1499606792151 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:32 INFO - 1499606792152 Marionette TRACE 360 <- [1,4,null,{}]
06:26:32 INFO - 1499606792153 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}]
06:26:32 INFO - TEST-START: test_click.py TestClick.test_container_element1499606792155 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:26:32 INFO - 1499606792156 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:26:32 INFO - 1499606792157 Marionette TRACE 360 <- [1,6,null,{}]
06:26:32 INFO - 1499606792159 Marionette DEBUG Closed connection 360
06:26:32 INFO - 1499606792159 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49801
06:26:32 INFO - 1499606792160 Marionette DEBUG Closed connection 361
06:26:32 INFO - 1499606792160 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49802
06:26:32 INFO - 1499606792161 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:32 INFO - 1499606792162 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792164 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792166 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b08bd96c-83ed-4f98-82a7-a5ecc7f92ffc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792167 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:26:32 INFO - 1499606792169 Marionette TRACE 362 <- [1,2,null,{}]
06:26:32 INFO - 1499606792170 Marionette DEBUG Closed connection 362
06:26:32 INFO - 1499606792170 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49803
06:26:32 INFO - 1499606792171 Marionette DEBUG Closed connection 363
06:26:32 INFO - 1499606792172 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49804
06:26:32 INFO - 1499606792173 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:32 INFO - 1499606792173 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792175 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792178 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0168190f-4b23-4f4e-9a44-0c147a9d0179","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792179 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"}]
06:26:32 INFO - 1499606792180 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49357/test.html"
06:26:32 INFO - 1499606792182 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49357/test.html"
06:26:32 INFO - 1499606792182 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49357/test.html"
06:26:32 INFO - 1499606792188 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"
06:26:32 INFO - 1499606792191 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"
06:26:32 INFO - 1499606792200 Marionette TRACE 364 <- [1,2,null,{}]
06:26:32 INFO - 1499606792203 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:26:32 INFO - 1499606792205 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bc5c7be0-188b-46db-b636-9ca691b02d80","ELEMENT":"bc5c7be0-188b-46db-b636-9ca691b02d80"}}]
06:26:32 INFO - 1499606792205 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"bc5c7be0-188b-46db-b636-9ca691b02d80"}]
06:26:32 INFO - 1499606792449 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:32 INFO - 1499606792450 Marionette TRACE 364 <- [1,4,null,{}]
06:26:32 INFO - 1499606792451 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"bc5c7be0-188b-46db-b636-9ca691b02d80","name":"selected"}]
06:26:32 INFO - 1499606792455 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:26:32 INFO - 1499606792457 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}]
06:26:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 323ms
06:26:32 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:26:32 INFO - TEST-END: test_click.py TestClick.test_container_element1499606792459 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:26:32 INFO - 1499606792460 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:26:32 INFO - 1499606792461 Marionette TRACE 364 <- [1,7,null,{}]
06:26:32 INFO - 1499606792462 Marionette DEBUG Closed connection 364
06:26:32 INFO - 1499606792463 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49805
06:26:32 INFO - 1499606792464 Marionette DEBUG Closed connection 365
06:26:32 INFO - 1499606792464 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49806
06:26:32 INFO - 1499606792465 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:32 INFO - 1499606792466 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792468 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792471 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9bad1899-9a15-4ff6-a766-88536f5cd8fb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792472 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:26:32 INFO - 1499606792472 Marionette TRACE 366 <- [1,2,null,{}]
06:26:32 INFO - 1499606792473 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:32 INFO - 1499606792474 Marionette TRACE 366 <- [1,3,null,{}]
06:26:32 INFO - 1499606792475 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:32 INFO - 1499606792475 Marionette TRACE 366 <- [1,4,null,{}]
06:26:32 INFO - 1499606792477 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}]
06:26:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499606792479 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:26:32 INFO - 1499606792480 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:26:32 INFO - 1499606792481 Marionette TRACE 366 <- [1,6,null,{}]
06:26:32 INFO - 1499606792482 Marionette DEBUG Closed connection 366
06:26:32 INFO - 1499606792483 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49807
06:26:32 INFO - 1499606792484 Marionette DEBUG Closed connection 367
06:26:32 INFO - 1499606792484 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49808
06:26:32 INFO - 1499606792485 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:32 INFO - 1499606792486 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792488 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792491 Marionette TRACE 368 <- [1,1,null,{"sessionId":"70e13b9a-f959-4630-bdd3-a398b6317cfa","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792492 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:26:32 INFO - 1499606792493 Marionette TRACE 368 <- [1,2,null,{}]
06:26:32 INFO - 1499606792494 Marionette DEBUG Closed connection 368
06:26:32 INFO - 1499606792495 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49809
06:26:32 INFO - 1499606792495 Marionette DEBUG Closed connection 369
06:26:32 INFO - 1499606792496 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49810
06:26:32 INFO - 1499606792497 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:32 INFO - 1499606792497 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792499 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792502 Marionette TRACE 370 <- [1,1,null,{"sessionId":"10305697-9bd4-42e4-9c91-58d98ab62078","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792503 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"}]
06:26:32 INFO - 1499606792505 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"
06:26:32 INFO - 1499606792507 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"
06:26:32 INFO - 1499606792507 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"
06:26:32 INFO - 1499606792514 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"
06:26:32 INFO - 1499606792515 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"
06:26:32 INFO - 1499606792520 Marionette TRACE 370 <- [1,2,null,{}]
06:26:32 INFO - 1499606792523 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:26:32 INFO - 1499606792525 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9de00aa0-43f3-4bd6-94ac-f698513bb5f7","ELEMENT":"9de00aa0-43f3-4bd6-94ac-f698513bb5f7"}}]
06:26:32 INFO - 1499606792526 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9de00aa0-43f3-4bd6-94ac-f698513bb5f7"}]
06:26:32 INFO - 1499606792764 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:32 INFO - 1499606792765 Marionette TRACE 370 <- [1,4,null,{}]
06:26:32 INFO - 1499606792766 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9de00aa0-43f3-4bd6-94ac-f698513bb5f7","name":"selected"}]
06:26:32 INFO - 1499606792770 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:26:32 INFO - 1499606792771 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}]
06:26:32 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499606792773 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:26:32 INFO - 1499606792774 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:26:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 314ms
06:26:32 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:26:32 INFO - 1499606792775 Marionette TRACE 370 <- [1,7,null,{}]
06:26:32 INFO - 1499606792776 Marionette DEBUG Closed connection 370
06:26:32 INFO - 1499606792777 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49811
06:26:32 INFO - 1499606792778 Marionette DEBUG Closed connection 371
06:26:32 INFO - 1499606792778 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49812
06:26:32 INFO - 1499606792779 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:32 INFO - 1499606792780 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792782 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792785 Marionette TRACE 372 <- [1,1,null,{"sessionId":"aba68c7a-cbad-4429-9a51-1ec79367b5c4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792786 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:26:32 INFO - 1499606792786 Marionette TRACE 372 <- [1,2,null,{}]
06:26:32 INFO - 1499606792787 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:32 INFO - 1499606792787 Marionette TRACE 372 <- [1,3,null,{}]
06:26:32 INFO - 1499606792788 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:32 INFO - 1499606792789 Marionette TRACE 372 <- [1,4,null,{}]
06:26:32 INFO - 1499606792790 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}]
06:26:32 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499606792792 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:26:32 INFO - 1499606792793 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:26:32 INFO - 1499606792794 Marionette TRACE 372 <- [1,6,null,{}]
06:26:32 INFO - 1499606792795 Marionette DEBUG Closed connection 372
06:26:32 INFO - 1499606792796 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49813
06:26:32 INFO - 1499606792797 Marionette DEBUG Closed connection 373
06:26:32 INFO - 1499606792797 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49814
06:26:32 INFO - 1499606792798 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:32 INFO - 1499606792799 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792801 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792803 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2aec5f98-9192-450a-b554-e9ba4795a046","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792804 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:26:32 INFO - 1499606792805 Marionette TRACE 374 <- [1,2,null,{}]
06:26:32 INFO - 1499606792807 Marionette DEBUG Closed connection 374
06:26:32 INFO - 1499606792807 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49815
06:26:32 INFO - 1499606792808 Marionette DEBUG Closed connection 375
06:26:32 INFO - 1499606792808 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49816
06:26:32 INFO - 1499606792809 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:32 INFO - 1499606792810 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:32 INFO - 1499606792812 Marionette DEBUG Register listener.js for window 4294967297
06:26:32 INFO - 1499606792815 Marionette TRACE 376 <- [1,1,null,{"sessionId":"990914ac-b7b0-4e90-92eb-8638881a311f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:32 INFO - 1499606792816 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"}]
06:26:32 INFO - 1499606792818 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"
06:26:32 INFO - 1499606792819 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"
06:26:32 INFO - 1499606792820 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"
06:26:32 INFO - 1499606792825 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"
06:26:32 INFO - 1499606792827 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"
06:26:32 INFO - 1499606792834 Marionette TRACE 376 <- [1,2,null,{}]
06:26:32 INFO - 1499606792837 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:26:32 INFO - 1499606792841 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d6fc99f-016c-43f0-839f-f7367162ac47","ELEMENT":"8d6fc99f-016c-43f0-839f-f7367162ac47"}}]
06:26:32 INFO - 1499606792842 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8d6fc99f-016c-43f0-839f-f7367162ac47"}]
06:26:33 INFO - 1499606793082 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:33 INFO - 1499606793083 Marionette TRACE 376 <- [1,4,null,{}]
06:26:33 INFO - 1499606793085 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}]
06:26:33 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499606793087 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:26:33 INFO - 1499606793088 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:26:33 INFO - 1499606793090 Marionette TRACE 376 <- [1,6,null,{}]
06:26:33 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 314ms
06:26:33 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:26:33 INFO - 1499606793091 Marionette DEBUG Closed connection 376
06:26:33 INFO - 1499606793093 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49817
06:26:33 INFO - 1499606793093 Marionette DEBUG Closed connection 377
06:26:33 INFO - 1499606793094 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49818
06:26:33 INFO - 1499606793095 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:33 INFO - 1499606793095 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793097 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793100 Marionette TRACE 378 <- [1,1,null,{"sessionId":"033a2176-03b9-4a0f-b4e7-eb9893f04353","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793101 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:26:33 INFO - 1499606793101 Marionette TRACE 378 <- [1,2,null,{}]
06:26:33 INFO - 1499606793102 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:33 INFO - 1499606793102 Marionette TRACE 378 <- [1,3,null,{}]
06:26:33 INFO - 1499606793103 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:33 INFO - 1499606793103 Marionette TRACE 378 <- [1,4,null,{}]
06:26:33 INFO - 1499606793105 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}]
06:26:33 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499606793107 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:26:33 INFO - 1499606793107 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:26:33 INFO - 1499606793109 Marionette TRACE 378 <- [1,6,null,{}]
06:26:33 INFO - 1499606793110 Marionette DEBUG Closed connection 378
06:26:33 INFO - 1499606793111 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49819
06:26:33 INFO - 1499606793111 Marionette DEBUG Closed connection 379
06:26:33 INFO - 1499606793112 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49820
06:26:33 INFO - 1499606793113 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:33 INFO - 1499606793113 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793115 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793118 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8f3bf1f7-94a9-4fb6-a63a-bc10355da80f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793119 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:26:33 INFO - 1499606793120 Marionette TRACE 380 <- [1,2,null,{}]
06:26:33 INFO - 1499606793121 Marionette DEBUG Closed connection 380
06:26:33 INFO - 1499606793122 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49821
06:26:33 INFO - 1499606793123 Marionette DEBUG Closed connection 381
06:26:33 INFO - 1499606793123 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49822
06:26:33 INFO - 1499606793124 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:33 INFO - 1499606793124 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793127 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793129 Marionette TRACE 382 <- [1,1,null,{"sessionId":"7c3a33fe-5a92-4feb-ac47-ba9d3b1b3e2c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793130 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"}]
06:26:33 INFO - 1499606793132 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"
06:26:33 INFO - 1499606793133 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"
06:26:33 INFO - 1499606793133 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"
06:26:33 INFO - 1499606793139 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"
06:26:33 INFO - 1499606793142 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"
06:26:33 INFO - 1499606793143 Marionette TRACE 382 <- [1,2,null,{}]
06:26:33 INFO - 1499606793149 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:26:33 INFO - 1499606793152 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c8925986-a909-484a-a4c9-c748f908eca2","ELEMENT":"c8925986-a909-484a-a4c9-c748f908eca2"}}]
06:26:33 INFO - 1499606793152 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c8925986-a909-484a-a4c9-c748f908eca2"}]
06:26:33 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 305ms
06:26:33 INFO - TEST-START | test_click.py TestClick.test_input_file
06:26:33 INFO - 1499606793389 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:33 INFO - 1499606793390 Marionette TRACE 382 <- [1,4,null,{}]
06:26:33 INFO - 1499606793392 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}]
06:26:33 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499606793394 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:26:33 INFO - 1499606793395 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:26:33 INFO - 1499606793396 Marionette TRACE 382 <- [1,6,null,{}]
06:26:33 INFO - 1499606793397 Marionette DEBUG Closed connection 382
06:26:33 INFO - 1499606793398 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49823
06:26:33 INFO - 1499606793399 Marionette DEBUG Closed connection 383
06:26:33 INFO - 1499606793400 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49824
06:26:33 INFO - 1499606793400 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:33 INFO - 1499606793401 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793403 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793405 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ad6e9139-52b9-4c03-b7c3-aa7b511eeb28","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793407 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:26:33 INFO - 1499606793407 Marionette TRACE 384 <- [1,2,null,{}]
06:26:33 INFO - 1499606793408 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:33 INFO - 1499606793408 Marionette TRACE 384 <- [1,3,null,{}]
06:26:33 INFO - 1499606793409 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:33 INFO - 1499606793409 Marionette TRACE 384 <- [1,4,null,{}]
06:26:33 INFO - 1499606793410 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}]
06:26:33 INFO - TEST-START: test_click.py TestClick.test_input_file1499606793413 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:26:33 INFO - 1499606793414 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:26:33 INFO - 1499606793415 Marionette TRACE 384 <- [1,6,null,{}]
06:26:33 INFO - 1499606793416 Marionette DEBUG Closed connection 384
06:26:33 INFO - 1499606793416 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49825
06:26:33 INFO - 1499606793417 Marionette DEBUG Closed connection 385
06:26:33 INFO - 1499606793418 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49826
06:26:33 INFO - 1499606793419 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:33 INFO - 1499606793420 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793422 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793432 Marionette TRACE 386 <- [1,1,null,{"sessionId":"2aca524b-fb4d-4f2f-81e2-172f76016493","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793434 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:26:33 INFO - 1499606793435 Marionette TRACE 386 <- [1,2,null,{}]
06:26:33 INFO - 1499606793436 Marionette DEBUG Closed connection 386
06:26:33 INFO - 1499606793437 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49827
06:26:33 INFO - 1499606793437 Marionette DEBUG Closed connection 387
06:26:33 INFO - 1499606793438 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49828
06:26:33 INFO - 1499606793439 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:33 INFO - 1499606793440 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793442 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793444 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2d887cf3-d2de-4d97-bb49-2aeeb854cef6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793446 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:26:33 INFO - 1499606793447 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"
06:26:33 INFO - 1499606793449 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"
06:26:33 INFO - 1499606793449 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"
06:26:33 INFO - 1499606793455 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:33 INFO - 1499606793458 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:33 INFO - 1499606793460 Marionette TRACE 388 <- [1,2,null,{}]
06:26:33 INFO - 1499606793465 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:26:33 INFO - 1499606793468 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9dace2cb-efbd-42d8-b154-ab0b163d998d","ELEMENT":"9dace2cb-efbd-42d8-b154-ab0b163d998d"}}]
06:26:33 INFO - 1499606793469 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"9dace2cb-efbd-42d8-b154-ab0b163d998d"}]
06:26:33 INFO - 1499606793498 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]
06:26:33 INFO - 1499606793500 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}]
06:26:33 INFO - TEST-END: test_click.py TestClick.test_input_file1499606793502 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:26:33 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 108ms
06:26:33 INFO - 1499606793503 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:26:33 INFO - 1499606793504 Marionette TRACE 388 <- [1,6,null,{}]
06:26:33 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:26:33 INFO - 1499606793506 Marionette DEBUG Closed connection 388
06:26:33 INFO - 1499606793507 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49829
06:26:33 INFO - 1499606793507 Marionette DEBUG Closed connection 389
06:26:33 INFO - 1499606793508 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49830
06:26:33 INFO - 1499606793509 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:33 INFO - 1499606793510 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793512 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793514 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9e5aa125-afae-4cb5-9cff-f9abe92f05d6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793516 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:26:33 INFO - 1499606793516 Marionette TRACE 390 <- [1,2,null,{}]
06:26:33 INFO - 1499606793517 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:33 INFO - 1499606793517 Marionette TRACE 390 <- [1,3,null,{}]
06:26:33 INFO - 1499606793518 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:33 INFO - 1499606793518 Marionette TRACE 390 <- [1,4,null,{}]
06:26:33 INFO - 1499606793520 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}]
06:26:33 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499606793522 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:26:33 INFO - 1499606793523 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:26:33 INFO - 1499606793524 Marionette TRACE 390 <- [1,6,null,{}]
06:26:33 INFO - 1499606793526 Marionette DEBUG Closed connection 390
06:26:33 INFO - 1499606793526 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49831
06:26:33 INFO - 1499606793527 Marionette DEBUG Closed connection 391
06:26:33 INFO - 1499606793527 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49832
06:26:33 INFO - 1499606793528 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:33 INFO - 1499606793529 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793531 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793533 Marionette TRACE 392 <- [1,1,null,{"sessionId":"99178f21-5ab4-4756-9902-0721f73f3838","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793534 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:26:33 INFO - 1499606793535 Marionette TRACE 392 <- [1,2,null,{}]
06:26:33 INFO - 1499606793537 Marionette DEBUG Closed connection 392
06:26:33 INFO - 1499606793537 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49833
06:26:33 INFO - 1499606793538 Marionette DEBUG Closed connection 393
06:26:33 INFO - 1499606793538 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49834
06:26:33 INFO - 1499606793539 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:33 INFO - 1499606793540 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793542 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793545 Marionette TRACE 394 <- [1,1,null,{"sessionId":"6d91f252-eb27-47d0-a359-0333465af4f2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793546 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"}]
06:26:33 INFO - 1499606793547 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:33 INFO - 1499606793549 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:33 INFO - 1499606793549 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:26:33 INFO - 1499606793554 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"
06:26:33 INFO - 1499606793557 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"
06:26:33 INFO - 1499606793558 Marionette TRACE 394 <- [1,2,null,{}]
06:26:33 INFO - 1499606793561 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:26:33 INFO - 1499606793566 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"12870411-9556-4d48-ac57-49a28c9bd3f1","ELEMENT":"12870411-9556-4d48-ac57-49a28c9bd3f1"}}]
06:26:33 INFO - 1499606793567 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:26:33 INFO - 1499606793569 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d541fa75-a57b-49f5-ba15-3484c8ec5cd1","ELEMENT":"d541fa75-a57b-49f5-ba15-3484c8ec5cd1"}}]
06:26:33 INFO - 1499606793570 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"12870411-9556-4d48-ac57-49a28c9bd3f1"}]
06:26:33 INFO - 1499606793804 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:26:33 INFO - 1499606793805 Marionette TRACE 394 <- [1,5,null,{}]
06:26:33 INFO - 1499606793806 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d541fa75-a57b-49f5-ba15-3484c8ec5cd1"}]
06:26:33 INFO - 1499606793820 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
06:26:33 INFO - 1499606793821 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:26:33 INFO - 1499606793824 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:26:33 INFO - 1499606793825 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}]
06:26:33 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499606793827 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:26:33 INFO - 1499606793828 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:26:33 INFO - 1499606793829 Marionette TRACE 394 <- [1,9,null,{}]
06:26:33 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 325ms
06:26:33 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:26:33 INFO - 1499606793831 Marionette DEBUG Closed connection 394
06:26:33 INFO - 1499606793832 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49835
06:26:33 INFO - 1499606793833 Marionette DEBUG Closed connection 395
06:26:33 INFO - 1499606793833 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49836
06:26:33 INFO - 1499606793834 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:33 INFO - 1499606793835 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793837 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793840 Marionette TRACE 396 <- [1,1,null,{"sessionId":"dbf568b6-9d94-47f0-ad2b-fc100db74cd2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793841 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:26:33 INFO - 1499606793842 Marionette TRACE 396 <- [1,2,null,{}]
06:26:33 INFO - 1499606793843 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:26:33 INFO - 1499606793843 Marionette TRACE 396 <- [1,3,null,{}]
06:26:33 INFO - 1499606793844 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:26:33 INFO - 1499606793844 Marionette TRACE 396 <- [1,4,null,{}]
06:26:33 INFO - 1499606793846 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}]
06:26:33 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499606793848 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:26:33 INFO - 1499606793849 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:26:33 INFO - 1499606793850 Marionette TRACE 396 <- [1,6,null,{}]
06:26:33 INFO - 1499606793851 Marionette DEBUG Closed connection 396
06:26:33 INFO - 1499606793852 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49837
06:26:33 INFO - 1499606793852 Marionette DEBUG Closed connection 397
06:26:33 INFO - 1499606793853 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49838
06:26:33 INFO - 1499606793854 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:26:33 INFO - 1499606793854 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793856 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793859 Marionette TRACE 398 <- [1,1,null,{"sessionId":"f619165e-707f-444e-b635-ca44c491abd2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793860 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:26:33 INFO - 1499606793861 Marionette TRACE 398 <- [1,2,null,{}]
06:26:33 INFO - 1499606793862 Marionette DEBUG Closed connection 398
06:26:33 INFO - 1499606793863 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49839
06:26:33 INFO - 1499606793864 Marionette DEBUG Closed connection 399
06:26:33 INFO - 1499606793864 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49840
06:26:33 INFO - 1499606793865 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:26:33 INFO - 1499606793866 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}
06:26:33 INFO - 1499606793868 Marionette DEBUG Register listener.js for window 4294967297
06:26:33 INFO - 1499606793871 Marionette TRACE 400 <- [1,1,null,{"sessionId":"31a37670-ad0f-437e-a3a2-9790dbf29215","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1628,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-121.001\\AppData\\Local\\Temp\\tmpaofsxo.mozrunner","moz:accessibilityChecks":false}}]
06:26:33 INFO - 1499606793872 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"}]
06:26:33 INFO - 1499606793874 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"
06:26:33 INFO - 1499606793875 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"
06:26:33 INFO - 1499606793875 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"
06:26:33 INFO - 1499606793882 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"
06:26:33 INFO - 1499606793885 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"
06:26:33 INFO - 1499606793887 Marionette TRACE 400 <- [1,2,null,{}]
06:26:33 INFO - 1499606793890 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:26:33 INFO - 1499606793892 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a60deca-2977-4224-903b-1af0c7aeb08a","ELEMENT":"9a60deca-2977-4224-903b-1af0c7aeb08a"}}]
06:26:33 INFO - 1499606793893 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9a60deca-2977-4224-903b-1af0c7aeb08a"}]
06:26:33 INFO - 1499606793921 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:26:33 INFO - 1499606793922 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9a60deca-2977-4224-903b-1af0c7aeb08a"}]
06:26:33 INFO - 1499606793937 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element