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]
04:49:42 INFO - 1499428182088 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}]
04:49:42 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499428182091 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:49:42 INFO - 1499428182092 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:49:42 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 143ms
04:49:42 INFO - 1499428182093 Marionette TRACE 352 <- [1,6,null,{}]
04:49:42 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:49:42 INFO - 1499428182096 Marionette DEBUG Closed connection 352
04:49:42 INFO - 1499428182096 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49750
04:49:42 INFO - 1499428182097 Marionette DEBUG Closed connection 353
04:49:42 INFO - 1499428182099 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49751
04:49:42 INFO - 1499428182112 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:42 INFO - 1499428182112 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182115 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182118 Marionette TRACE 354 <- [1,1,null,{"sessionId":"8cce1f78-7c26-4699-a0b1-64b92ad73967","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182120 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:49:42 INFO - 1499428182121 Marionette TRACE 354 <- [1,2,null,{}]
04:49:42 INFO - 1499428182121 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:49:42 INFO - 1499428182122 Marionette TRACE 354 <- [1,3,null,{}]
04:49:42 INFO - 1499428182123 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:49:42 INFO - 1499428182123 Marionette TRACE 354 <- [1,4,null,{}]
04:49:42 INFO - 1499428182126 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}]
04:49:42 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499428182128 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:49:42 INFO - 1499428182129 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:49:42 INFO - 1499428182131 Marionette TRACE 354 <- [1,6,null,{}]
04:49:42 INFO - 1499428182133 Marionette DEBUG Closed connection 354
04:49:42 INFO - 1499428182134 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49752
04:49:42 INFO - 1499428182135 Marionette DEBUG Closed connection 355
04:49:42 INFO - 1499428182135 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49753
04:49:42 INFO - 1499428182136 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:42 INFO - 1499428182137 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182140 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182143 Marionette TRACE 356 <- [1,1,null,{"sessionId":"83f6a7de-19db-416c-94c1-44519b66168d","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182152 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:49:42 INFO - 1499428182153 Marionette TRACE 356 <- [1,2,null,{}]
04:49:42 INFO - 1499428182156 Marionette DEBUG Closed connection 356
04:49:42 INFO - 1499428182156 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49754
04:49:42 INFO - 1499428182157 Marionette DEBUG Closed connection 357
04:49:42 INFO - 1499428182158 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49755
04:49:42 INFO - 1499428182159 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:49:42 INFO - 1499428182159 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182163 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182166 Marionette TRACE 358 <- [1,1,null,{"sessionId":"607efbd9-c488-46df-83a0-a33b55a3a5f3","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182168 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49321/clicks.html"}]
04:49:42 INFO - 1499428182169 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49321/hidden.html"
04:49:42 INFO - 1499428182175 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49321/hidden.html"
04:49:42 INFO - 1499428182175 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49321/hidden.html"
04:49:42 INFO - 1499428182203 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49321/clicks.html"
04:49:42 INFO - 1499428182205 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49321/clicks.html"
04:49:42 INFO - 1499428182208 Marionette TRACE 358 <- [1,2,null,{}]
04:49:42 INFO - 1499428182209 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:49:42 INFO - 1499428182217 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9c1b695a-527e-4471-9333-8daf2620123f","ELEMENT":"9c1b695a-527e-4471-9333-8daf2620123f"}}]
04:49:42 INFO - 1499428182218 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"9c1b695a-527e-4471-9333-8daf2620123f"}]
04:49:42 INFO - 1499428182272 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49321/clicks.html"
04:49:42 INFO - 1499428182281 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49321/clicks.html"
04:49:42 INFO - 1499428182282 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49321/clicks.html"
04:49:42 INFO - 1499428182318 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49321/test.html"
04:49:42 INFO - 1499428182320 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49321/test.html"
04:49:42 INFO - 1499428182322 Marionette TRACE 358 <- [1,4,null,{}]
04:49:42 INFO - 1499428182323 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:49:42 INFO - 1499428182326 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"991004a5-3b25-494f-83ed-df373d4fae1b","ELEMENT":"991004a5-3b25-494f-83ed-df373d4fae1b"}}]
04:49:42 INFO - 1499428182326 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:49:42 INFO - 1499428182327 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:49:42 INFO - 1499428182338 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}]
04:49:42 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499428182340 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:49:42 INFO - 1499428182341 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:49:42 INFO - 1499428182342 Marionette TRACE 358 <- [1,8,null,{}]
04:49:42 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 248ms
04:49:42 INFO - TEST-START | test_click.py TestClick.test_container_element
04:49:42 INFO - 1499428182345 Marionette DEBUG Closed connection 358
04:49:42 INFO - 1499428182346 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49758
04:49:42 INFO - 1499428182350 Marionette DEBUG Closed connection 359
04:49:42 INFO - 1499428182351 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49759
04:49:42 INFO - 1499428182352 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:42 INFO - 1499428182353 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182355 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182359 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b934dd3a-5aed-42c9-b7c4-828a98366f7c","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182362 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:49:42 INFO - 1499428182363 Marionette TRACE 360 <- [1,2,null,{}]
04:49:42 INFO - 1499428182363 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:49:42 INFO - 1499428182364 Marionette TRACE 360 <- [1,3,null,{}]
04:49:42 INFO - 1499428182365 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:49:42 INFO - 1499428182365 Marionette TRACE 360 <- [1,4,null,{}]
04:49:42 INFO - 1499428182367 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}]
04:49:42 INFO - TEST-START: test_click.py TestClick.test_container_element1499428182370 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:49:42 INFO - 1499428182371 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:49:42 INFO - 1499428182372 Marionette TRACE 360 <- [1,6,null,{}]
04:49:42 INFO - 1499428182374 Marionette DEBUG Closed connection 360
04:49:42 INFO - 1499428182375 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49760
04:49:42 INFO - 1499428182376 Marionette DEBUG Closed connection 361
04:49:42 INFO - 1499428182376 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49761
04:49:42 INFO - 1499428182378 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:42 INFO - 1499428182379 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182382 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182385 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a553bee4-4176-4274-ba3d-f0c8765592af","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182387 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:49:42 INFO - 1499428182388 Marionette TRACE 362 <- [1,2,null,{}]
04:49:42 INFO - 1499428182390 Marionette DEBUG Closed connection 362
04:49:42 INFO - 1499428182391 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49762
04:49:42 INFO - 1499428182392 Marionette DEBUG Closed connection 363
04:49:42 INFO - 1499428182392 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49763
04:49:42 INFO - 1499428182393 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:49:42 INFO - 1499428182394 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182397 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182400 Marionette TRACE 364 <- [1,1,null,{"sessionId":"602c799c-63cd-4832-80a2-c3436122c500","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182402 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"}]
04:49:42 INFO - 1499428182404 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49321/test.html"
04:49:42 INFO - 1499428182406 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49321/test.html"
04:49:42 INFO - 1499428182406 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49321/test.html"
04:49:42 INFO - 1499428182434 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"
04:49:42 INFO - 1499428182436 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"
04:49:42 INFO - 1499428182439 Marionette TRACE 364 <- [1,2,null,{}]
04:49:42 INFO - 1499428182440 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:49:42 INFO - 1499428182443 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"edf00a30-ed02-4ad9-8aaf-976059fb028b","ELEMENT":"edf00a30-ed02-4ad9-8aaf-976059fb028b"}}]
04:49:42 INFO - 1499428182444 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"edf00a30-ed02-4ad9-8aaf-976059fb028b"}]
04:49:42 INFO - 1499428182699 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:49:42 INFO - 1499428182700 Marionette TRACE 364 <- [1,4,null,{}]
04:49:42 INFO - 1499428182701 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"edf00a30-ed02-4ad9-8aaf-976059fb028b","name":"selected"}]
04:49:42 INFO - 1499428182706 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:49:42 INFO - 1499428182707 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}]
04:49:42 INFO - TEST-END: test_click.py TestClick.test_container_element1499428182710 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:49:42 INFO - 1499428182711 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:49:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
04:49:42 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:49:42 INFO - 1499428182712 Marionette TRACE 364 <- [1,7,null,{}]
04:49:42 INFO - 1499428182714 Marionette DEBUG Closed connection 364
04:49:42 INFO - 1499428182715 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49764
04:49:42 INFO - 1499428182716 Marionette DEBUG Closed connection 365
04:49:42 INFO - 1499428182716 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49765
04:49:42 INFO - 1499428182718 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:42 INFO - 1499428182718 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182721 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182724 Marionette TRACE 366 <- [1,1,null,{"sessionId":"12a4eb8c-6169-4160-98d5-a0992f206c1b","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182726 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:49:42 INFO - 1499428182727 Marionette TRACE 366 <- [1,2,null,{}]
04:49:42 INFO - 1499428182728 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:49:42 INFO - 1499428182729 Marionette TRACE 366 <- [1,3,null,{}]
04:49:42 INFO - 1499428182730 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:49:42 INFO - 1499428182730 Marionette TRACE 366 <- [1,4,null,{}]
04:49:42 INFO - 1499428182731 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}]
04:49:42 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499428182734 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:49:42 INFO - 1499428182735 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:49:42 INFO - 1499428182736 Marionette TRACE 366 <- [1,6,null,{}]
04:49:42 INFO - 1499428182739 Marionette DEBUG Closed connection 366
04:49:42 INFO - 1499428182739 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49766
04:49:42 INFO - 1499428182740 Marionette DEBUG Closed connection 367
04:49:42 INFO - 1499428182740 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49767
04:49:42 INFO - 1499428182741 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:42 INFO - 1499428182742 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182745 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182749 Marionette TRACE 368 <- [1,1,null,{"sessionId":"2b441985-8f74-4568-861b-0d9cffbe8b24","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182751 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:49:42 INFO - 1499428182752 Marionette TRACE 368 <- [1,2,null,{}]
04:49:42 INFO - 1499428182754 Marionette DEBUG Closed connection 368
04:49:42 INFO - 1499428182755 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49768
04:49:42 INFO - 1499428182756 Marionette DEBUG Closed connection 369
04:49:42 INFO - 1499428182756 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49769
04:49:42 INFO - 1499428182757 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:49:42 INFO - 1499428182758 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:42 INFO - 1499428182760 Marionette DEBUG Register listener.js for window 12
04:49:42 INFO - 1499428182763 Marionette TRACE 370 <- [1,1,null,{"sessionId":"5b4f6b0b-ccaf-45f8-854c-a28c4c58b601","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:42 INFO - 1499428182765 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"}]
04:49:42 INFO - 1499428182767 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"
04:49:42 INFO - 1499428182769 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"
04:49:42 INFO - 1499428182769 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"
04:49:42 INFO - 1499428182789 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"
04:49:42 INFO - 1499428182791 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"
04:49:42 INFO - 1499428182800 Marionette TRACE 370 <- [1,2,null,{}]
04:49:42 INFO - 1499428182809 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:49:42 INFO - 1499428182811 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1acfd6cd-5bcb-41b5-b5aa-c5c657bfee57","ELEMENT":"1acfd6cd-5bcb-41b5-b5aa-c5c657bfee57"}}]
04:49:42 INFO - 1499428182813 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1acfd6cd-5bcb-41b5-b5aa-c5c657bfee57"}]
04:49:43 INFO - 1499428183055 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:49:43 INFO - 1499428183056 Marionette TRACE 370 <- [1,4,null,{}]
04:49:43 INFO - 1499428183058 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1acfd6cd-5bcb-41b5-b5aa-c5c657bfee57","name":"selected"}]
04:49:43 INFO - 1499428183062 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:49:43 INFO - 1499428183063 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}]
04:49:43 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499428183066 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:49:43 INFO - 1499428183067 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:49:43 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 356ms
04:49:43 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:49:43 INFO - 1499428183068 Marionette TRACE 370 <- [1,7,null,{}]
04:49:43 INFO - 1499428183071 Marionette DEBUG Closed connection 370
04:49:43 INFO - 1499428183071 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49770
04:49:43 INFO - 1499428183072 Marionette DEBUG Closed connection 371
04:49:43 INFO - 1499428183073 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49771
04:49:43 INFO - 1499428183074 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:43 INFO - 1499428183074 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183077 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183081 Marionette TRACE 372 <- [1,1,null,{"sessionId":"da20d861-9306-49e4-ab1f-160ed1be68b3","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183084 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:49:43 INFO - 1499428183085 Marionette TRACE 372 <- [1,2,null,{}]
04:49:43 INFO - 1499428183086 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:49:43 INFO - 1499428183087 Marionette TRACE 372 <- [1,3,null,{}]
04:49:43 INFO - 1499428183088 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:49:43 INFO - 1499428183089 Marionette TRACE 372 <- [1,4,null,{}]
04:49:43 INFO - 1499428183090 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}]
04:49:43 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499428183093 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:49:43 INFO - 1499428183094 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:49:43 INFO - 1499428183095 Marionette TRACE 372 <- [1,6,null,{}]
04:49:43 INFO - 1499428183098 Marionette DEBUG Closed connection 372
04:49:43 INFO - 1499428183098 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49772
04:49:43 INFO - 1499428183099 Marionette DEBUG Closed connection 373
04:49:43 INFO - 1499428183100 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49773
04:49:43 INFO - 1499428183101 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:43 INFO - 1499428183102 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183104 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183107 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d91dcdd0-8dd2-4a35-b584-d9c55c76d4f5","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183110 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:49:43 INFO - 1499428183111 Marionette TRACE 374 <- [1,2,null,{}]
04:49:43 INFO - 1499428183113 Marionette DEBUG Closed connection 374
04:49:43 INFO - 1499428183114 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49774
04:49:43 INFO - 1499428183114 Marionette DEBUG Closed connection 375
04:49:43 INFO - 1499428183115 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49775
04:49:43 INFO - 1499428183116 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:49:43 INFO - 1499428183117 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183120 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183131 Marionette TRACE 376 <- [1,1,null,{"sessionId":"57736c4d-8cb1-4261-947c-c1556b1a7ca2","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183133 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"}]
04:49:43 INFO - 1499428183134 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"
04:49:43 INFO - 1499428183137 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"
04:49:43 INFO - 1499428183137 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"
04:49:43 INFO - 1499428183157 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"
04:49:43 INFO - 1499428183161 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"
04:49:43 INFO - 1499428183168 Marionette TRACE 376 <- [1,2,null,{}]
04:49:43 INFO - 1499428183170 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:49:43 INFO - 1499428183173 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d2d1aab1-ccf2-4b8f-a0fb-e1de64ba83a8","ELEMENT":"d2d1aab1-ccf2-4b8f-a0fb-e1de64ba83a8"}}]
04:49:43 INFO - 1499428183174 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d2d1aab1-ccf2-4b8f-a0fb-e1de64ba83a8"}]
04:49:43 INFO - 1499428183414 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:49:43 INFO - 1499428183414 Marionette TRACE 376 <- [1,4,null,{}]
04:49:43 INFO - 1499428183416 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}]
04:49:43 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499428183419 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:49:43 INFO - 1499428183420 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:49:43 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 353ms
04:49:43 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:49:43 INFO - 1499428183422 Marionette TRACE 376 <- [1,6,null,{}]
04:49:43 INFO - 1499428183424 Marionette DEBUG Closed connection 376
04:49:43 INFO - 1499428183424 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49776
04:49:43 INFO - 1499428183425 Marionette DEBUG Closed connection 377
04:49:43 INFO - 1499428183426 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49777
04:49:43 INFO - 1499428183427 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:43 INFO - 1499428183428 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183430 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183433 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4e90cf8c-b6ec-4156-a00d-8200c461f2f4","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183436 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:49:43 INFO - 1499428183436 Marionette TRACE 378 <- [1,2,null,{}]
04:49:43 INFO - 1499428183437 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:49:43 INFO - 1499428183438 Marionette TRACE 378 <- [1,3,null,{}]
04:49:43 INFO - 1499428183439 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:49:43 INFO - 1499428183439 Marionette TRACE 378 <- [1,4,null,{}]
04:49:43 INFO - 1499428183441 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}]
04:49:43 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499428183443 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:49:43 INFO - 1499428183444 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:49:43 INFO - 1499428183446 Marionette TRACE 378 <- [1,6,null,{}]
04:49:43 INFO - 1499428183448 Marionette DEBUG Closed connection 378
04:49:43 INFO - 1499428183448 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49778
04:49:43 INFO - 1499428183449 Marionette DEBUG Closed connection 379
04:49:43 INFO - 1499428183450 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49779
04:49:43 INFO - 1499428183451 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:43 INFO - 1499428183451 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183454 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183457 Marionette TRACE 380 <- [1,1,null,{"sessionId":"6597177f-cbc1-43b3-b439-1519b5e2ead6","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183459 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:49:43 INFO - 1499428183461 Marionette TRACE 380 <- [1,2,null,{}]
04:49:43 INFO - 1499428183463 Marionette DEBUG Closed connection 380
04:49:43 INFO - 1499428183464 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49780
04:49:43 INFO - 1499428183465 Marionette DEBUG Closed connection 381
04:49:43 INFO - 1499428183465 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49781
04:49:43 INFO - 1499428183466 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:49:43 INFO - 1499428183467 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183470 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183472 Marionette TRACE 382 <- [1,1,null,{"sessionId":"852d07d5-2a15-407e-ae02-84a4270cad94","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183475 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"}]
04:49:43 INFO - 1499428183476 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"
04:49:43 INFO - 1499428183478 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"
04:49:43 INFO - 1499428183479 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"
04:49:43 INFO - 1499428183491 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"
04:49:43 INFO - 1499428183495 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"
04:49:43 INFO - 1499428183505 Marionette TRACE 382 <- [1,2,null,{}]
04:49:43 INFO - 1499428183507 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:49:43 INFO - 1499428183510 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"81696991-c6d2-4d62-beab-62575ad3482b","ELEMENT":"81696991-c6d2-4d62-beab-62575ad3482b"}}]
04:49:43 INFO - 1499428183511 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"81696991-c6d2-4d62-beab-62575ad3482b"}]
04:49:43 INFO - 1499428183795 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:49:43 INFO - 1499428183796 Marionette TRACE 382 <- [1,4,null,{}]
04:49:43 INFO - 1499428183799 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}]
04:49:43 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499428183803 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:49:43 INFO - 1499428183805 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:49:43 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 387ms
04:49:43 INFO - 1499428183809 Marionette TRACE 382 <- [1,6,null,{}]
04:49:43 INFO - TEST-START | test_click.py TestClick.test_input_file
04:49:43 INFO - 1499428183812 Marionette DEBUG Closed connection 382
04:49:43 INFO - 1499428183813 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49782
04:49:43 INFO - 1499428183831 Marionette DEBUG Closed connection 383
04:49:43 INFO - 1499428183831 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49783
04:49:43 INFO - 1499428183837 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:43 INFO - 1499428183841 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183844 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183851 Marionette TRACE 384 <- [1,1,null,{"sessionId":"66026e43-6a75-4627-a491-509651f5135e","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183866 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:49:43 INFO - 1499428183866 Marionette TRACE 384 <- [1,2,null,{}]
04:49:43 INFO - 1499428183900 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:49:43 INFO - 1499428183902 Marionette TRACE 384 <- [1,3,null,{}]
04:49:43 INFO - 1499428183903 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:49:43 INFO - 1499428183904 Marionette TRACE 384 <- [1,4,null,{}]
04:49:43 INFO - 1499428183906 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}]
04:49:43 INFO - TEST-START: test_click.py TestClick.test_input_file1499428183913 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:49:43 INFO - 1499428183915 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:49:43 INFO - 1499428183918 Marionette TRACE 384 <- [1,6,null,{}]
04:49:43 INFO - 1499428183922 Marionette DEBUG Closed connection 384
04:49:43 INFO - 1499428183924 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49784
04:49:43 INFO - 1499428183925 Marionette DEBUG Closed connection 385
04:49:43 INFO - 1499428183926 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49785
04:49:43 INFO - 1499428183928 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:43 INFO - 1499428183930 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183935 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183940 Marionette TRACE 386 <- [1,1,null,{"sessionId":"bd245e38-03c9-4a12-b140-0e4c9f8bbd35","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183943 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:49:43 INFO - 1499428183945 Marionette TRACE 386 <- [1,2,null,{}]
04:49:43 INFO - 1499428183948 Marionette DEBUG Closed connection 386
04:49:43 INFO - 1499428183948 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49786
04:49:43 INFO - 1499428183950 Marionette DEBUG Closed connection 387
04:49:43 INFO - 1499428183950 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49787
04:49:43 INFO - 1499428183953 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:49:43 INFO - 1499428183955 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:43 INFO - 1499428183958 Marionette DEBUG Register listener.js for window 12
04:49:43 INFO - 1499428183963 Marionette TRACE 388 <- [1,1,null,{"sessionId":"13c23775-02ce-4cdd-a822-396d5451e61d","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:43 INFO - 1499428183965 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:49:43 INFO - 1499428183967 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"
04:49:43 INFO - 1499428183972 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"
04:49:43 INFO - 1499428183973 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"
04:49:44 INFO - 1499428184001 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:49:44 INFO - 1499428184004 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:49:44 INFO - 1499428184009 Marionette TRACE 388 <- [1,2,null,{}]
04:49:44 INFO - 1499428184010 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:49:44 INFO - 1499428184030 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a1ca4dc-7543-4472-b61c-cf97a24d3660","ELEMENT":"5a1ca4dc-7543-4472-b61c-cf97a24d3660"}}]
04:49:44 INFO - 1499428184032 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"5a1ca4dc-7543-4472-b61c-cf97a24d3660"}]
04:49:44 INFO - 1499428184068 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]
04:49:44 INFO - 1499428184070 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}]
04:49:44 INFO - TEST-END: test_click.py TestClick.test_input_file1499428184073 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:49:44 INFO - 1499428184074 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:49:44 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 267ms
04:49:44 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:49:44 INFO - 1499428184076 Marionette TRACE 388 <- [1,6,null,{}]
04:49:44 INFO - 1499428184078 Marionette DEBUG Closed connection 388
04:49:44 INFO - 1499428184079 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49788
04:49:44 INFO - 1499428184080 Marionette DEBUG Closed connection 389
04:49:44 INFO - 1499428184081 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49789
04:49:44 INFO - 1499428184082 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:44 INFO - 1499428184083 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:44 INFO - 1499428184086 Marionette DEBUG Register listener.js for window 12
04:49:44 INFO - 1499428184090 Marionette TRACE 390 <- [1,1,null,{"sessionId":"69c8eced-8876-4f2b-9eec-a5671de7392f","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:44 INFO - 1499428184093 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:49:44 INFO - 1499428184094 Marionette TRACE 390 <- [1,2,null,{}]
04:49:44 INFO - 1499428184095 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:49:44 INFO - 1499428184095 Marionette TRACE 390 <- [1,3,null,{}]
04:49:44 INFO - 1499428184096 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:49:44 INFO - 1499428184097 Marionette TRACE 390 <- [1,4,null,{}]
04:49:44 INFO - 1499428184098 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}]
04:49:44 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499428184102 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:49:44 INFO - 1499428184103 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:49:44 INFO - 1499428184104 Marionette TRACE 390 <- [1,6,null,{}]
04:49:44 INFO - 1499428184106 Marionette DEBUG Closed connection 390
04:49:44 INFO - 1499428184107 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49790
04:49:44 INFO - 1499428184108 Marionette DEBUG Closed connection 391
04:49:44 INFO - 1499428184109 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49791
04:49:44 INFO - 1499428184110 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:44 INFO - 1499428184111 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:44 INFO - 1499428184113 Marionette DEBUG Register listener.js for window 12
04:49:44 INFO - 1499428184119 Marionette TRACE 392 <- [1,1,null,{"sessionId":"106ed418-06f9-4216-9e80-6946de2162df","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:44 INFO - 1499428184121 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:49:44 INFO - 1499428184123 Marionette TRACE 392 <- [1,2,null,{}]
04:49:44 INFO - 1499428184127 Marionette DEBUG Closed connection 392
04:49:44 INFO - 1499428184129 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49792
04:49:44 INFO - 1499428184140 Marionette DEBUG Closed connection 393
04:49:44 INFO - 1499428184140 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49793
04:49:44 INFO - 1499428184143 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:49:44 INFO - 1499428184144 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:44 INFO - 1499428184147 Marionette DEBUG Register listener.js for window 12
04:49:44 INFO - 1499428184151 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f596f176-e5ba-474d-a913-766a8ccd22ec","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:44 INFO - 1499428184154 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"}]
04:49:44 INFO - 1499428184156 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:49:44 INFO - 1499428184159 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:49:44 INFO - 1499428184159 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:49:44 INFO - 1499428184186 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"
04:49:44 INFO - 1499428184189 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"
04:49:44 INFO - 1499428184193 Marionette TRACE 394 <- [1,2,null,{}]
04:49:44 INFO - 1499428184202 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:49:44 INFO - 1499428184205 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"282dbc7e-751e-4628-8979-1d6671c6a5e6","ELEMENT":"282dbc7e-751e-4628-8979-1d6671c6a5e6"}}]
04:49:44 INFO - 1499428184206 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:49:44 INFO - 1499428184210 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"825719c9-50ae-4597-a19e-f9ca548272c2","ELEMENT":"825719c9-50ae-4597-a19e-f9ca548272c2"}}]
04:49:44 INFO - 1499428184211 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"282dbc7e-751e-4628-8979-1d6671c6a5e6"}]
04:49:44 INFO - 1499428184453 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:49:44 INFO - 1499428184454 Marionette TRACE 394 <- [1,5,null,{}]
04:49:44 INFO - 1499428184455 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"825719c9-50ae-4597-a19e-f9ca548272c2"}]
04:49:44 INFO - 1499428184473 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]
04:49:44 INFO - 1499428184475 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:49:44 INFO - 1499428184478 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:49:44 INFO - 1499428184480 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}]
04:49:44 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499428184483 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:49:44 INFO - 1499428184484 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:49:44 INFO - 1499428184485 Marionette TRACE 394 <- [1,9,null,{}]
04:49:44 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 409ms
04:49:44 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:49:44 INFO - 1499428184488 Marionette DEBUG Closed connection 394
04:49:44 INFO - 1499428184488 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49794
04:49:44 INFO - 1499428184489 Marionette DEBUG Closed connection 395
04:49:44 INFO - 1499428184490 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49795
04:49:44 INFO - 1499428184491 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:44 INFO - 1499428184492 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:44 INFO - 1499428184495 Marionette DEBUG Register listener.js for window 12
04:49:44 INFO - 1499428184498 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ac16cb78-bf13-4b45-beb2-73c5094ca1b9","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:44 INFO - 1499428184500 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:49:44 INFO - 1499428184501 Marionette TRACE 396 <- [1,2,null,{}]
04:49:44 INFO - 1499428184502 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:49:44 INFO - 1499428184502 Marionette TRACE 396 <- [1,3,null,{}]
04:49:44 INFO - 1499428184503 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:49:44 INFO - 1499428184504 Marionette TRACE 396 <- [1,4,null,{}]
04:49:44 INFO - 1499428184505 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}]
04:49:44 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499428184508 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:49:44 INFO - 1499428184509 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:49:44 INFO - 1499428184510 Marionette TRACE 396 <- [1,6,null,{}]
04:49:44 INFO - 1499428184513 Marionette DEBUG Closed connection 396
04:49:44 INFO - 1499428184513 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49796
04:49:44 INFO - 1499428184514 Marionette DEBUG Closed connection 397
04:49:44 INFO - 1499428184515 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49797
04:49:44 INFO - 1499428184516 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:49:44 INFO - 1499428184517 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:44 INFO - 1499428184520 Marionette DEBUG Register listener.js for window 12
04:49:44 INFO - 1499428184523 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d017f89a-b7a4-4ac0-8710-71ea9b47c22d","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:44 INFO - 1499428184526 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:49:44 INFO - 1499428184527 Marionette TRACE 398 <- [1,2,null,{}]
04:49:44 INFO - 1499428184529 Marionette DEBUG Closed connection 398
04:49:44 INFO - 1499428184530 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49798
04:49:44 INFO - 1499428184531 Marionette DEBUG Closed connection 399
04:49:44 INFO - 1499428184532 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49799
04:49:44 INFO - 1499428184533 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:49:44 INFO - 1499428184534 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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}
04:49:44 INFO - 1499428184537 Marionette DEBUG Register listener.js for window 12
04:49:44 INFO - 1499428184540 Marionette TRACE 400 <- [1,1,null,{"sessionId":"fe8632ae-3c8e-41e1-bfae-287193f84dd3","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":2352,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-233.000\\AppData\\Local\\Temp\\tmpytxvo2.mozrunner","moz:accessibilityChecks":false}}]
04:49:44 INFO - 1499428184543 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"}]
04:49:44 INFO - 1499428184544 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"
04:49:44 INFO - 1499428184547 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"
04:49:44 INFO - 1499428184547 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"
04:49:44 INFO - 1499428184571 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"
04:49:44 INFO - 1499428184573 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"
04:49:44 INFO - 1499428184577 Marionette TRACE 400 <- [1,2,null,{}]
04:49:44 INFO - 1499428184583 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:49:44 INFO - 1499428184586 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2bc4f680-85a7-4c90-a8aa-93a57c6c478b","ELEMENT":"2bc4f680-85a7-4c90-a8aa-93a57c6c478b"}}]
04:49:44 INFO - 1499428184588 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2bc4f680-85a7-4c90-a8aa-93a57c6c478b"}]
04:49:44 INFO - 1499428184621 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:49:44 INFO - 1499428184623 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"2bc4f680-85a7-4c90-a8aa-93a57c6c478b"}]
04:49:44 INFO - 1499428184641 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element