could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:21:26 INFO - 1499167286827 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:21:26 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499167286830 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:21:26 INFO - 1499167286831 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:21:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 149ms
04:21:26 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:21:26 INFO - 1499167286833 Marionette TRACE 352 <- [1,6,null,{}]
04:21:26 INFO - 1499167286834 Marionette DEBUG Closed connection 352
04:21:26 INFO - 1499167286835 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49791
04:21:26 INFO - 1499167286836 Marionette DEBUG Closed connection 353
04:21:26 INFO - 1499167286837 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49792
04:21:26 INFO - 1499167286838 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:26 INFO - 1499167286839 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:26 INFO - 1499167286842 Marionette DEBUG Register listener.js for window 4294967297
04:21:26 INFO - 1499167286845 Marionette TRACE 354 <- [1,1,null,{"sessionId":"93f19901-8e3d-4d33-a745-3d7b18a5a4ce","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:26 INFO - 1499167286846 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:21:26 INFO - 1499167286847 Marionette TRACE 354 <- [1,2,null,{}]
04:21:26 INFO - 1499167286848 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:21:26 INFO - 1499167286848 Marionette TRACE 354 <- [1,3,null,{}]
04:21:26 INFO - 1499167286849 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:21:26 INFO - 1499167286850 Marionette TRACE 354 <- [1,4,null,{}]
04:21:26 INFO - 1499167286852 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:21:26 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499167286855 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:21:26 INFO - 1499167286856 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:21:26 INFO - 1499167286857 Marionette TRACE 354 <- [1,6,null,{}]
04:21:26 INFO - 1499167286859 Marionette DEBUG Closed connection 354
04:21:26 INFO - 1499167286860 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49793
04:21:26 INFO - 1499167286861 Marionette DEBUG Closed connection 355
04:21:26 INFO - 1499167286861 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49794
04:21:26 INFO - 1499167286862 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:26 INFO - 1499167286863 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:26 INFO - 1499167286866 Marionette DEBUG Register listener.js for window 4294967297
04:21:26 INFO - 1499167286870 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5743d341-919a-45c6-a50c-cd2ed34e3743","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:26 INFO - 1499167286871 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:21:26 INFO - 1499167286873 Marionette TRACE 356 <- [1,2,null,{}]
04:21:26 INFO - 1499167286874 Marionette DEBUG Closed connection 356
04:21:26 INFO - 1499167286875 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49795
04:21:26 INFO - 1499167286876 Marionette DEBUG Closed connection 357
04:21:26 INFO - 1499167286877 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49796
04:21:26 INFO - 1499167286878 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:21:26 INFO - 1499167286879 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:26 INFO - 1499167286882 Marionette DEBUG Register listener.js for window 4294967297
04:21:26 INFO - 1499167286885 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0e4b4fd0-5776-4a4f-aeae-92ebe756166c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:26 INFO - 1499167286887 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49345/clicks.html"}]
04:21:26 INFO - 1499167286889 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49345/hidden.html"
04:21:26 INFO - 1499167286898 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49345/hidden.html"
04:21:26 INFO - 1499167286899 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49345/hidden.html"
04:21:26 INFO - 1499167286907 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49345/clicks.html"
04:21:26 INFO - 1499167286913 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49345/clicks.html"
04:21:26 INFO - 1499167286926 Marionette TRACE 358 <- [1,2,null,{}]
04:21:26 INFO - 1499167286942 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:21:26 INFO - 1499167286945 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8c5c61dc-8178-4d21-87ba-5edd61b79b0b","ELEMENT":"8c5c61dc-8178-4d21-87ba-5edd61b79b0b"}}]
04:21:26 INFO - 1499167286946 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"8c5c61dc-8178-4d21-87ba-5edd61b79b0b"}]
04:21:26 INFO - 1499167286991 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49345/clicks.html"
04:21:27 INFO - 1499167287000 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49345/clicks.html"
04:21:27 INFO - 1499167287001 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49345/clicks.html"
04:21:27 INFO - 1499167287011 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49345/test.html"
04:21:27 INFO - 1499167287018 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49345/test.html"
04:21:27 INFO - 1499167287023 Marionette TRACE 358 <- [1,4,null,{}]
04:21:27 INFO - 1499167287030 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:21:27 INFO - 1499167287033 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ba5f6626-3dd8-49a1-b24b-406b7ca89c85","ELEMENT":"ba5f6626-3dd8-49a1-b24b-406b7ca89c85"}}]
04:21:27 INFO - 1499167287041 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:21:27 INFO - 1499167287041 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:21:27 INFO - 1499167287043 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:21:27 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499167287046 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:21:27 INFO - 1499167287047 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:21:27 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 216ms
04:21:27 INFO - TEST-START | test_click.py TestClick.test_container_element
04:21:27 INFO - 1499167287049 Marionette TRACE 358 <- [1,8,null,{}]
04:21:27 INFO - 1499167287051 Marionette DEBUG Closed connection 358
04:21:27 INFO - 1499167287052 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49799
04:21:27 INFO - 1499167287053 Marionette DEBUG Closed connection 359
04:21:27 INFO - 1499167287054 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49800
04:21:27 INFO - 1499167287055 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:27 INFO - 1499167287055 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287059 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287063 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3012dbd0-4926-48af-b45b-6ba509e96345","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287064 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:21:27 INFO - 1499167287065 Marionette TRACE 360 <- [1,2,null,{}]
04:21:27 INFO - 1499167287066 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:21:27 INFO - 1499167287066 Marionette TRACE 360 <- [1,3,null,{}]
04:21:27 INFO - 1499167287067 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:21:27 INFO - 1499167287068 Marionette TRACE 360 <- [1,4,null,{}]
04:21:27 INFO - 1499167287070 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:21:27 INFO - TEST-START: test_click.py TestClick.test_container_element1499167287073 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:21:27 INFO - 1499167287074 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:21:27 INFO - 1499167287076 Marionette TRACE 360 <- [1,6,null,{}]
04:21:27 INFO - 1499167287077 Marionette DEBUG Closed connection 360
04:21:27 INFO - 1499167287078 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49801
04:21:27 INFO - 1499167287079 Marionette DEBUG Closed connection 361
04:21:27 INFO - 1499167287080 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49802
04:21:27 INFO - 1499167287081 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:27 INFO - 1499167287082 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287085 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287089 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b43ea222-cc5a-49c3-96e7-6a3ed83ac96a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287090 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:21:27 INFO - 1499167287091 Marionette TRACE 362 <- [1,2,null,{}]
04:21:27 INFO - 1499167287093 Marionette DEBUG Closed connection 362
04:21:27 INFO - 1499167287094 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49803
04:21:27 INFO - 1499167287095 Marionette DEBUG Closed connection 363
04:21:27 INFO - 1499167287096 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49804
04:21:27 INFO - 1499167287097 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:21:27 INFO - 1499167287097 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287100 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287104 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a6016b44-5713-4b96-9375-ec7605b1ae6c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287105 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:21:27 INFO - 1499167287107 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49345/test.html"
04:21:27 INFO - 1499167287110 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49345/test.html"
04:21:27 INFO - 1499167287110 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49345/test.html"
04:21:27 INFO - 1499167287122 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:21:27 INFO - 1499167287124 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:21:27 INFO - 1499167287137 Marionette TRACE 364 <- [1,2,null,{}]
04:21:27 INFO - 1499167287143 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:21:27 INFO - 1499167287155 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0655cced-800a-44cb-806a-80c253c7a5c8","ELEMENT":"0655cced-800a-44cb-806a-80c253c7a5c8"}}]
04:21:27 INFO - 1499167287157 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0655cced-800a-44cb-806a-80c253c7a5c8"}]
04:21:27 INFO - 1499167287405 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:21:27 INFO - 1499167287406 Marionette TRACE 364 <- [1,4,null,{}]
04:21:27 INFO - 1499167287407 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0655cced-800a-44cb-806a-80c253c7a5c8","name":"selected"}]
04:21:27 INFO - 1499167287412 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:21:27 INFO - 1499167287414 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:21:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 371ms
04:21:27 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:21:27 INFO - TEST-END: test_click.py TestClick.test_container_element1499167287417 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:21:27 INFO - 1499167287419 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:21:27 INFO - 1499167287420 Marionette TRACE 364 <- [1,7,null,{}]
04:21:27 INFO - 1499167287422 Marionette DEBUG Closed connection 364
04:21:27 INFO - 1499167287423 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49805
04:21:27 INFO - 1499167287424 Marionette DEBUG Closed connection 365
04:21:27 INFO - 1499167287424 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49806
04:21:27 INFO - 1499167287425 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:27 INFO - 1499167287426 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287429 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287433 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4de87b9b-d55b-4548-9a2d-60df3fea19bf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287434 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:21:27 INFO - 1499167287434 Marionette TRACE 366 <- [1,2,null,{}]
04:21:27 INFO - 1499167287435 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:21:27 INFO - 1499167287436 Marionette TRACE 366 <- [1,3,null,{}]
04:21:27 INFO - 1499167287437 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:21:27 INFO - 1499167287437 Marionette TRACE 366 <- [1,4,null,{}]
04:21:27 INFO - 1499167287439 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:21:27 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499167287442 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:21:27 INFO - 1499167287443 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:21:27 INFO - 1499167287445 Marionette TRACE 366 <- [1,6,null,{}]
04:21:27 INFO - 1499167287446 Marionette DEBUG Closed connection 366
04:21:27 INFO - 1499167287447 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49807
04:21:27 INFO - 1499167287448 Marionette DEBUG Closed connection 367
04:21:27 INFO - 1499167287449 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49808
04:21:27 INFO - 1499167287450 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:27 INFO - 1499167287451 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287454 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287457 Marionette TRACE 368 <- [1,1,null,{"sessionId":"01b7da63-d529-46db-9f28-30aca208ed02","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287459 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:21:27 INFO - 1499167287460 Marionette TRACE 368 <- [1,2,null,{}]
04:21:27 INFO - 1499167287462 Marionette DEBUG Closed connection 368
04:21:27 INFO - 1499167287463 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49809
04:21:27 INFO - 1499167287464 Marionette DEBUG Closed connection 369
04:21:27 INFO - 1499167287464 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49810
04:21:27 INFO - 1499167287466 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:21:27 INFO - 1499167287466 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287469 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287473 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c0381a89-6002-4d23-b80d-96dad4e242df","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287474 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:21:27 INFO - 1499167287476 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:21:27 INFO - 1499167287478 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:21:27 INFO - 1499167287479 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:21:27 INFO - 1499167287489 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:21:27 INFO - 1499167287491 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:21:27 INFO - 1499167287499 Marionette TRACE 370 <- [1,2,null,{}]
04:21:27 INFO - 1499167287507 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:21:27 INFO - 1499167287514 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"65b6261f-c945-4402-94b7-a7b513a217ad","ELEMENT":"65b6261f-c945-4402-94b7-a7b513a217ad"}}]
04:21:27 INFO - 1499167287516 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"65b6261f-c945-4402-94b7-a7b513a217ad"}]
04:21:27 INFO - 1499167287763 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:21:27 INFO - 1499167287764 Marionette TRACE 370 <- [1,4,null,{}]
04:21:27 INFO - 1499167287765 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"65b6261f-c945-4402-94b7-a7b513a217ad","name":"selected"}]
04:21:27 INFO - 1499167287770 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:21:27 INFO - 1499167287772 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:21:27 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499167287775 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:21:27 INFO - 1499167287776 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:21:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 358ms
04:21:27 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:21:27 INFO - 1499167287778 Marionette TRACE 370 <- [1,7,null,{}]
04:21:27 INFO - 1499167287780 Marionette DEBUG Closed connection 370
04:21:27 INFO - 1499167287781 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49811
04:21:27 INFO - 1499167287781 Marionette DEBUG Closed connection 371
04:21:27 INFO - 1499167287782 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49812
04:21:27 INFO - 1499167287783 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:27 INFO - 1499167287784 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287787 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287791 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2fc47c8b-a446-4d8d-944f-c5cbb1ff5308","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287792 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:21:27 INFO - 1499167287792 Marionette TRACE 372 <- [1,2,null,{}]
04:21:27 INFO - 1499167287793 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:21:27 INFO - 1499167287794 Marionette TRACE 372 <- [1,3,null,{}]
04:21:27 INFO - 1499167287795 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:21:27 INFO - 1499167287795 Marionette TRACE 372 <- [1,4,null,{}]
04:21:27 INFO - 1499167287797 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:21:27 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499167287801 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:21:27 INFO - 1499167287802 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:21:27 INFO - 1499167287803 Marionette TRACE 372 <- [1,6,null,{}]
04:21:27 INFO - 1499167287805 Marionette DEBUG Closed connection 372
04:21:27 INFO - 1499167287806 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49813
04:21:27 INFO - 1499167287807 Marionette DEBUG Closed connection 373
04:21:27 INFO - 1499167287807 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49814
04:21:27 INFO - 1499167287809 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:27 INFO - 1499167287809 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287812 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287816 Marionette TRACE 374 <- [1,1,null,{"sessionId":"b51f9312-7a00-4475-a9eb-67c6f0f6bb13","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287818 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:21:27 INFO - 1499167287819 Marionette TRACE 374 <- [1,2,null,{}]
04:21:27 INFO - 1499167287821 Marionette DEBUG Closed connection 374
04:21:27 INFO - 1499167287822 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49815
04:21:27 INFO - 1499167287823 Marionette DEBUG Closed connection 375
04:21:27 INFO - 1499167287823 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49816
04:21:27 INFO - 1499167287825 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:21:27 INFO - 1499167287825 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:27 INFO - 1499167287828 Marionette DEBUG Register listener.js for window 4294967297
04:21:27 INFO - 1499167287832 Marionette TRACE 376 <- [1,1,null,{"sessionId":"15f576ac-75a0-4b17-846f-91489dd1008d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:27 INFO - 1499167287833 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:21:27 INFO - 1499167287835 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:21:27 INFO - 1499167287837 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:21:27 INFO - 1499167287838 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:21:27 INFO - 1499167287845 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:21:27 INFO - 1499167287848 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:21:27 INFO - 1499167287859 Marionette TRACE 376 <- [1,2,null,{}]
04:21:27 INFO - 1499167287864 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:21:27 INFO - 1499167287872 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a95288d-3169-4d7d-834f-b0e5194b9fcd","ELEMENT":"9a95288d-3169-4d7d-834f-b0e5194b9fcd"}}]
04:21:27 INFO - 1499167287873 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"9a95288d-3169-4d7d-834f-b0e5194b9fcd"}]
04:21:28 INFO - 1499167288121 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:21:28 INFO - 1499167288122 Marionette TRACE 376 <- [1,4,null,{}]
04:21:28 INFO - 1499167288124 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:21:28 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499167288127 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:21:28 INFO - 1499167288128 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:21:28 INFO - 1499167288130 Marionette TRACE 376 <- [1,6,null,{}]
04:21:28 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 352ms
04:21:28 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:21:28 INFO - 1499167288132 Marionette DEBUG Closed connection 376
04:21:28 INFO - 1499167288133 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49817
04:21:28 INFO - 1499167288134 Marionette DEBUG Closed connection 377
04:21:28 INFO - 1499167288135 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49818
04:21:28 INFO - 1499167288136 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:28 INFO - 1499167288136 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288140 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288143 Marionette TRACE 378 <- [1,1,null,{"sessionId":"48d85d35-8775-4cde-bb60-1d935de7eddf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288144 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:21:28 INFO - 1499167288145 Marionette TRACE 378 <- [1,2,null,{}]
04:21:28 INFO - 1499167288146 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:21:28 INFO - 1499167288147 Marionette TRACE 378 <- [1,3,null,{}]
04:21:28 INFO - 1499167288148 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:21:28 INFO - 1499167288148 Marionette TRACE 378 <- [1,4,null,{}]
04:21:28 INFO - 1499167288150 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:21:28 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499167288153 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:21:28 INFO - 1499167288154 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:21:28 INFO - 1499167288156 Marionette TRACE 378 <- [1,6,null,{}]
04:21:28 INFO - 1499167288158 Marionette DEBUG Closed connection 378
04:21:28 INFO - 1499167288159 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49819
04:21:28 INFO - 1499167288160 Marionette DEBUG Closed connection 379
04:21:28 INFO - 1499167288160 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49820
04:21:28 INFO - 1499167288161 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:28 INFO - 1499167288162 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288165 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288168 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a4a4793e-8ec6-42a0-9dfb-50d4e21fb538","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288169 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:21:28 INFO - 1499167288171 Marionette TRACE 380 <- [1,2,null,{}]
04:21:28 INFO - 1499167288172 Marionette DEBUG Closed connection 380
04:21:28 INFO - 1499167288173 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49821
04:21:28 INFO - 1499167288174 Marionette DEBUG Closed connection 381
04:21:28 INFO - 1499167288175 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49822
04:21:28 INFO - 1499167288176 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:21:28 INFO - 1499167288177 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288180 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288183 Marionette TRACE 382 <- [1,1,null,{"sessionId":"9400f9b6-61b4-4fe2-b297-8270324942b6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288184 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:21:28 INFO - 1499167288186 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:21:28 INFO - 1499167288189 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:21:28 INFO - 1499167288189 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:21:28 INFO - 1499167288196 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:21:28 INFO - 1499167288200 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:21:28 INFO - 1499167288208 Marionette TRACE 382 <- [1,2,null,{}]
04:21:28 INFO - 1499167288213 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:21:28 INFO - 1499167288216 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"759af9cc-de4a-4dab-a277-dbd235de93fa","ELEMENT":"759af9cc-de4a-4dab-a277-dbd235de93fa"}}]
04:21:28 INFO - 1499167288222 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"759af9cc-de4a-4dab-a277-dbd235de93fa"}]
04:21:28 INFO - 1499167288473 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:21:28 INFO - 1499167288474 Marionette TRACE 382 <- [1,4,null,{}]
04:21:28 INFO - 1499167288476 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:21:28 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499167288479 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:21:28 INFO - 1499167288480 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:21:28 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 352ms
04:21:28 INFO - TEST-START | test_click.py TestClick.test_input_file
04:21:28 INFO - 1499167288482 Marionette TRACE 382 <- [1,6,null,{}]
04:21:28 INFO - 1499167288484 Marionette DEBUG Closed connection 382
04:21:28 INFO - 1499167288485 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49823
04:21:28 INFO - 1499167288485 Marionette DEBUG Closed connection 383
04:21:28 INFO - 1499167288486 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49824
04:21:28 INFO - 1499167288487 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:28 INFO - 1499167288488 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288491 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288503 Marionette TRACE 384 <- [1,1,null,{"sessionId":"d078d811-0def-4c30-bdf0-7af3f9b8ef6d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288505 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:21:28 INFO - 1499167288506 Marionette TRACE 384 <- [1,2,null,{}]
04:21:28 INFO - 1499167288507 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:21:28 INFO - 1499167288507 Marionette TRACE 384 <- [1,3,null,{}]
04:21:28 INFO - 1499167288508 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:21:28 INFO - 1499167288509 Marionette TRACE 384 <- [1,4,null,{}]
04:21:28 INFO - 1499167288510 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:21:28 INFO - TEST-START: test_click.py TestClick.test_input_file1499167288514 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:21:28 INFO - 1499167288515 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:21:28 INFO - 1499167288516 Marionette TRACE 384 <- [1,6,null,{}]
04:21:28 INFO - 1499167288518 Marionette DEBUG Closed connection 384
04:21:28 INFO - 1499167288519 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49825
04:21:28 INFO - 1499167288520 Marionette DEBUG Closed connection 385
04:21:28 INFO - 1499167288521 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49826
04:21:28 INFO - 1499167288522 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:28 INFO - 1499167288523 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288526 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288529 Marionette TRACE 386 <- [1,1,null,{"sessionId":"2e4cf6dc-71cd-4879-846f-379fa596a210","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288530 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:21:28 INFO - 1499167288532 Marionette TRACE 386 <- [1,2,null,{}]
04:21:28 INFO - 1499167288533 Marionette DEBUG Closed connection 386
04:21:28 INFO - 1499167288534 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49827
04:21:28 INFO - 1499167288535 Marionette DEBUG Closed connection 387
04:21:28 INFO - 1499167288536 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49828
04:21:28 INFO - 1499167288537 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:21:28 INFO - 1499167288538 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288541 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288544 Marionette TRACE 388 <- [1,1,null,{"sessionId":"042664d0-c6a0-469d-af8b-31d9dbb54869","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288546 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:21:28 INFO - 1499167288548 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:21:28 INFO - 1499167288550 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:21:28 INFO - 1499167288551 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:21:28 INFO - 1499167288558 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:21:28 INFO - 1499167288564 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:21:28 INFO - 1499167288565 Marionette TRACE 388 <- [1,2,null,{}]
04:21:28 INFO - 1499167288580 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:21:28 INFO - 1499167288588 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"865a88f0-1836-4b93-9a86-59ecb5a710de","ELEMENT":"865a88f0-1836-4b93-9a86-59ecb5a710de"}}]
04:21:28 INFO - 1499167288589 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"865a88f0-1836-4b93-9a86-59ecb5a710de"}]
04:21:28 INFO - 1499167288618 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:21:28 INFO - 1499167288620 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:21:28 INFO - TEST-END: test_click.py TestClick.test_input_file1499167288623 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:21:28 INFO - 1499167288624 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:21:28 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 144ms
04:21:28 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:21:28 INFO - 1499167288626 Marionette TRACE 388 <- [1,6,null,{}]
04:21:28 INFO - 1499167288628 Marionette DEBUG Closed connection 388
04:21:28 INFO - 1499167288628 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49829
04:21:28 INFO - 1499167288629 Marionette DEBUG Closed connection 389
04:21:28 INFO - 1499167288630 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49830
04:21:28 INFO - 1499167288631 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:28 INFO - 1499167288632 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288635 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288639 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9a88171c-df15-4743-bb94-bc13df8227a3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288640 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:21:28 INFO - 1499167288641 Marionette TRACE 390 <- [1,2,null,{}]
04:21:28 INFO - 1499167288641 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:21:28 INFO - 1499167288642 Marionette TRACE 390 <- [1,3,null,{}]
04:21:28 INFO - 1499167288643 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:21:28 INFO - 1499167288643 Marionette TRACE 390 <- [1,4,null,{}]
04:21:28 INFO - 1499167288645 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:21:28 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499167288648 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:21:28 INFO - 1499167288649 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:21:28 INFO - 1499167288651 Marionette TRACE 390 <- [1,6,null,{}]
04:21:28 INFO - 1499167288653 Marionette DEBUG Closed connection 390
04:21:28 INFO - 1499167288654 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49831
04:21:28 INFO - 1499167288654 Marionette DEBUG Closed connection 391
04:21:28 INFO - 1499167288655 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49832
04:21:28 INFO - 1499167288656 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:28 INFO - 1499167288657 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288660 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288663 Marionette TRACE 392 <- [1,1,null,{"sessionId":"65a3faa7-514d-4044-886d-7eae957f8a25","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288665 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:21:28 INFO - 1499167288666 Marionette TRACE 392 <- [1,2,null,{}]
04:21:28 INFO - 1499167288668 Marionette DEBUG Closed connection 392
04:21:28 INFO - 1499167288669 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49833
04:21:28 INFO - 1499167288670 Marionette DEBUG Closed connection 393
04:21:28 INFO - 1499167288670 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49834
04:21:28 INFO - 1499167288672 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:21:28 INFO - 1499167288672 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:28 INFO - 1499167288675 Marionette DEBUG Register listener.js for window 4294967297
04:21:28 INFO - 1499167288679 Marionette TRACE 394 <- [1,1,null,{"sessionId":"bbf590c8-1bfa-40fb-b77d-17af33f1f6dd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:28 INFO - 1499167288680 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:21:28 INFO - 1499167288682 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:21:28 INFO - 1499167288684 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:21:28 INFO - 1499167288685 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:21:28 INFO - 1499167288693 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:21:28 INFO - 1499167288696 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:21:28 INFO - 1499167288698 Marionette TRACE 394 <- [1,2,null,{}]
04:21:28 INFO - 1499167288709 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:21:28 INFO - 1499167288713 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a5d13e2-0542-4ae5-a441-5472eb1ee114","ELEMENT":"8a5d13e2-0542-4ae5-a441-5472eb1ee114"}}]
04:21:28 INFO - 1499167288714 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:21:28 INFO - 1499167288718 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5b72fbf2-439e-4e2e-a36c-a8d0de6a542c","ELEMENT":"5b72fbf2-439e-4e2e-a36c-a8d0de6a542c"}}]
04:21:28 INFO - 1499167288719 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"8a5d13e2-0542-4ae5-a441-5472eb1ee114"}]
04:21:28 INFO - 1499167288960 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:21:28 INFO - 1499167288961 Marionette TRACE 394 <- [1,5,null,{}]
04:21:28 INFO - 1499167288962 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"5b72fbf2-439e-4e2e-a36c-a8d0de6a542c"}]
04:21:28 INFO - 1499167288981 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
04:21:28 INFO - 1499167288984 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:21:28 INFO - 1499167288987 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:21:28 INFO - 1499167288989 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:21:28 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499167288992 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:21:28 INFO - 1499167288993 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:21:28 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 368ms
04:21:28 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:21:28 INFO - 1499167288995 Marionette TRACE 394 <- [1,9,null,{}]
04:21:28 INFO - 1499167288997 Marionette DEBUG Closed connection 394
04:21:28 INFO - 1499167288997 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49835
04:21:29 INFO - 1499167288998 Marionette DEBUG Closed connection 395
04:21:29 INFO - 1499167288999 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49836
04:21:29 INFO - 1499167289000 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:29 INFO - 1499167289001 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:29 INFO - 1499167289004 Marionette DEBUG Register listener.js for window 4294967297
04:21:29 INFO - 1499167289007 Marionette TRACE 396 <- [1,1,null,{"sessionId":"fbe082a2-6c03-4a85-89fe-d39e9391559f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:29 INFO - 1499167289009 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:21:29 INFO - 1499167289009 Marionette TRACE 396 <- [1,2,null,{}]
04:21:29 INFO - 1499167289010 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:21:29 INFO - 1499167289011 Marionette TRACE 396 <- [1,3,null,{}]
04:21:29 INFO - 1499167289012 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:21:29 INFO - 1499167289012 Marionette TRACE 396 <- [1,4,null,{}]
04:21:29 INFO - 1499167289014 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:21:29 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499167289017 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:21:29 INFO - 1499167289018 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:21:29 INFO - 1499167289020 Marionette TRACE 396 <- [1,6,null,{}]
04:21:29 INFO - 1499167289022 Marionette DEBUG Closed connection 396
04:21:29 INFO - 1499167289022 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49837
04:21:29 INFO - 1499167289023 Marionette DEBUG Closed connection 397
04:21:29 INFO - 1499167289024 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49838
04:21:29 INFO - 1499167289025 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:21:29 INFO - 1499167289026 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:29 INFO - 1499167289029 Marionette DEBUG Register listener.js for window 4294967297
04:21:29 INFO - 1499167289033 Marionette TRACE 398 <- [1,1,null,{"sessionId":"2f132744-6d00-4ea3-892b-7c76a0067922","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:29 INFO - 1499167289034 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:21:29 INFO - 1499167289036 Marionette TRACE 398 <- [1,2,null,{}]
04:21:29 INFO - 1499167289037 Marionette DEBUG Closed connection 398
04:21:29 INFO - 1499167289038 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49839
04:21:29 INFO - 1499167289039 Marionette DEBUG Closed connection 399
04:21:29 INFO - 1499167289040 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49840
04:21:29 INFO - 1499167289041 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:21:29 INFO - 1499167289042 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}
04:21:29 INFO - 1499167289046 Marionette DEBUG Register listener.js for window 4294967297
04:21:29 INFO - 1499167289049 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b1012ab0-2796-4cfa-8f08-c14049db8f4f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2464,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpprxnn0.mozrunner","moz:accessibilityChecks":false}}]
04:21:29 INFO - 1499167289051 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:21:29 INFO - 1499167289053 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:21:29 INFO - 1499167289055 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:21:29 INFO - 1499167289056 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:21:29 INFO - 1499167289063 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:21:29 INFO - 1499167289066 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:21:29 INFO - 1499167289076 Marionette TRACE 400 <- [1,2,null,{}]
04:21:29 INFO - 1499167289081 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:21:29 INFO - 1499167289089 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa3689d8-bb65-4ad8-8308-b088a93e3c4d","ELEMENT":"fa3689d8-bb65-4ad8-8308-b088a93e3c4d"}}]
04:21:29 INFO - 1499167289090 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"fa3689d8-bb65-4ad8-8308-b088a93e3c4d"}]
04:21:29 INFO - 1499167289119 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:21:29 INFO - 1499167289121 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"fa3689d8-bb65-4ad8-8308-b088a93e3c4d"}]
04:21:29 INFO - 1499167289139 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element