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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
02:17:38 INFO - 1500110258867 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}]
02:17:38 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500110258869 Marionette TRACE 352 <- [1,5,null,{"value":null}]
02:17:38 INFO - 1500110258870 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
02:17:38 INFO - 1500110258872 Marionette TRACE 352 <- [1,6,null,{}]
02:17:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 177ms
02:17:38 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
02:17:38 INFO - 1500110258874 Marionette DEBUG Closed connection 352
02:17:38 INFO - 1500110258875 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49827
02:17:38 INFO - 1500110258876 Marionette DEBUG Closed connection 353
02:17:38 INFO - 1500110258876 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49828
02:17:38 INFO - 1500110258878 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:38 INFO - 1500110258878 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:38 INFO - 1500110258881 Marionette DEBUG Register listener.js for window 15
02:17:38 INFO - 1500110258884 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b797f7b0-7b76-4fb6-8d01-fe308853949d","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:38 INFO - 1500110258886 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
02:17:38 INFO - 1500110258887 Marionette TRACE 354 <- [1,2,null,{}]
02:17:38 INFO - 1500110258888 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:17:38 INFO - 1500110258890 Marionette TRACE 354 <- [1,3,null,{}]
02:17:38 INFO - 1500110258891 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
02:17:38 INFO - 1500110258891 Marionette TRACE 354 <- [1,4,null,{}]
02:17:38 INFO - 1500110258893 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}]
02:17:38 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500110258896 Marionette TRACE 354 <- [1,5,null,{"value":null}]
02:17:38 INFO - 1500110258897 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
02:17:38 INFO - 1500110258898 Marionette TRACE 354 <- [1,6,null,{}]
02:17:38 INFO - 1500110258901 Marionette DEBUG Closed connection 354
02:17:38 INFO - 1500110258901 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49829
02:17:38 INFO - 1500110258902 Marionette DEBUG Closed connection 355
02:17:38 INFO - 1500110258903 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49830
02:17:38 INFO - 1500110258905 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:38 INFO - 1500110258905 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:38 INFO - 1500110258908 Marionette DEBUG Register listener.js for window 15
02:17:38 INFO - 1500110258912 Marionette TRACE 356 <- [1,1,null,{"sessionId":"49f73e28-96a8-43b4-a412-4cc26a7a8b5f","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:38 INFO - 1500110258914 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
02:17:38 INFO - 1500110258915 Marionette TRACE 356 <- [1,2,null,{}]
02:17:38 INFO - 1500110258918 Marionette DEBUG Closed connection 356
02:17:38 INFO - 1500110258918 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49831
02:17:38 INFO - 1500110258919 Marionette DEBUG Closed connection 357
02:17:38 INFO - 1500110258920 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49832
02:17:38 INFO - 1500110258921 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:17:38 INFO - 1500110258921 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:38 INFO - 1500110258924 Marionette DEBUG Register listener.js for window 15
02:17:38 INFO - 1500110258928 Marionette TRACE 358 <- [1,1,null,{"sessionId":"84692823-b9ee-48e4-ab56-1fdad1202727","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:38 INFO - 1500110258931 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49392/clicks.html"}]
02:17:38 INFO - 1500110258932 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49392/hidden.html"
02:17:38 INFO - 1500110258938 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49392/hidden.html"
02:17:38 INFO - 1500110258938 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49392/hidden.html"
02:17:38 INFO - 1500110258964 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49392/clicks.html"
02:17:38 INFO - 1500110258966 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49392/clicks.html"
02:17:38 INFO - 1500110258970 Marionette TRACE 358 <- [1,2,null,{}]
02:17:38 INFO - 1500110258972 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
02:17:38 INFO - 1500110258981 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"73793a96-8e45-4359-9394-b1071d5eeb62","ELEMENT":"73793a96-8e45-4359-9394-b1071d5eeb62"}}]
02:17:38 INFO - 1500110258983 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"73793a96-8e45-4359-9394-b1071d5eeb62"}]
02:17:39 INFO - 1500110259035 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49392/clicks.html"
02:17:39 INFO - 1500110259039 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49392/clicks.html"
02:17:39 INFO - 1500110259040 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49392/clicks.html"
02:17:39 INFO - 1500110259064 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49392/test.html"
02:17:39 INFO - 1500110259065 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49392/test.html"
02:17:39 INFO - 1500110259068 Marionette TRACE 358 <- [1,4,null,{}]
02:17:39 INFO - 1500110259069 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
02:17:39 INFO - 1500110259071 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7492416-5214-47d7-96b1-283b5bf345d8","ELEMENT":"c7492416-5214-47d7-96b1-283b5bf345d8"}}]
02:17:39 INFO - 1500110259072 Marionette TRACE 358 -> [0,6,"getTitle",{}]
02:17:39 INFO - 1500110259072 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
02:17:39 INFO - 1500110259079 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}]
02:17:39 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500110259082 Marionette TRACE 358 <- [1,7,null,{"value":null}]
02:17:39 INFO - 1500110259083 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
02:17:39 INFO - 1500110259085 Marionette TRACE 358 <- [1,8,null,{}]
02:17:39 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 212ms
02:17:39 INFO - TEST-START | test_click.py TestClick.test_container_element
02:17:39 INFO - 1500110259087 Marionette DEBUG Closed connection 358
02:17:39 INFO - 1500110259087 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49835
02:17:39 INFO - 1500110259088 Marionette DEBUG Closed connection 359
02:17:39 INFO - 1500110259089 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49836
02:17:39 INFO - 1500110259090 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:39 INFO - 1500110259091 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259094 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259098 Marionette TRACE 360 <- [1,1,null,{"sessionId":"69c45157-678e-4e63-8719-78b3c97e2333","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259100 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
02:17:39 INFO - 1500110259101 Marionette TRACE 360 <- [1,2,null,{}]
02:17:39 INFO - 1500110259102 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:17:39 INFO - 1500110259102 Marionette TRACE 360 <- [1,3,null,{}]
02:17:39 INFO - 1500110259103 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
02:17:39 INFO - 1500110259103 Marionette TRACE 360 <- [1,4,null,{}]
02:17:39 INFO - 1500110259105 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}]
02:17:39 INFO - TEST-START: test_click.py TestClick.test_container_element1500110259110 Marionette TRACE 360 <- [1,5,null,{"value":null}]
02:17:39 INFO - 1500110259111 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
02:17:39 INFO - 1500110259112 Marionette TRACE 360 <- [1,6,null,{}]
02:17:39 INFO - 1500110259115 Marionette DEBUG Closed connection 360
02:17:39 INFO - 1500110259115 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49837
02:17:39 INFO - 1500110259116 Marionette DEBUG Closed connection 361
02:17:39 INFO - 1500110259117 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49838
02:17:39 INFO - 1500110259118 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:39 INFO - 1500110259118 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259121 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259126 Marionette TRACE 362 <- [1,1,null,{"sessionId":"a1e9de6e-20e4-419e-9f25-e67d77b3e634","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259128 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
02:17:39 INFO - 1500110259130 Marionette TRACE 362 <- [1,2,null,{}]
02:17:39 INFO - 1500110259132 Marionette DEBUG Closed connection 362
02:17:39 INFO - 1500110259132 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49839
02:17:39 INFO - 1500110259133 Marionette DEBUG Closed connection 363
02:17:39 INFO - 1500110259134 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49840
02:17:39 INFO - 1500110259135 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:17:39 INFO - 1500110259136 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259138 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259143 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f6864771-8e88-4b45-b44b-76430736869c","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259145 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"}]
02:17:39 INFO - 1500110259147 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49392/test.html"
02:17:39 INFO - 1500110259149 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49392/test.html"
02:17:39 INFO - 1500110259149 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49392/test.html"
02:17:39 INFO - 1500110259172 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"
02:17:39 INFO - 1500110259180 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"
02:17:39 INFO - 1500110259183 Marionette TRACE 364 <- [1,2,null,{}]
02:17:39 INFO - 1500110259185 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:17:39 INFO - 1500110259187 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1ca5128e-018b-4cef-aa73-065d68c00802","ELEMENT":"1ca5128e-018b-4cef-aa73-065d68c00802"}}]
02:17:39 INFO - 1500110259188 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"1ca5128e-018b-4cef-aa73-065d68c00802"}]
02:17:39 INFO - 1500110259441 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:17:39 INFO - 1500110259441 Marionette TRACE 364 <- [1,4,null,{}]
02:17:39 INFO - 1500110259443 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"1ca5128e-018b-4cef-aa73-065d68c00802","name":"selected"}]
02:17:39 INFO - 1500110259447 Marionette TRACE 364 <- [1,5,null,{"value":true}]
02:17:39 INFO - 1500110259449 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}]
02:17:39 INFO - TEST-END: test_click.py TestClick.test_container_element1500110259451 Marionette TRACE 364 <- [1,6,null,{"value":null}]
02:17:39 INFO - 1500110259452 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
02:17:39 INFO - 1500110259454 Marionette TRACE 364 <- [1,7,null,{}]
02:17:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
02:17:39 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
02:17:39 INFO - 1500110259456 Marionette DEBUG Closed connection 364
02:17:39 INFO - 1500110259457 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49841
02:17:39 INFO - 1500110259458 Marionette DEBUG Closed connection 365
02:17:39 INFO - 1500110259458 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49842
02:17:39 INFO - 1500110259460 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:39 INFO - 1500110259460 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259463 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259466 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ccc64922-e146-4ef4-b5b0-4e27ed353965","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259474 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
02:17:39 INFO - 1500110259475 Marionette TRACE 366 <- [1,2,null,{}]
02:17:39 INFO - 1500110259476 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:17:39 INFO - 1500110259477 Marionette TRACE 366 <- [1,3,null,{}]
02:17:39 INFO - 1500110259478 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
02:17:39 INFO - 1500110259480 Marionette TRACE 366 <- [1,4,null,{}]
02:17:39 INFO - 1500110259481 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}]
02:17:39 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500110259485 Marionette TRACE 366 <- [1,5,null,{"value":null}]
02:17:39 INFO - 1500110259486 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
02:17:39 INFO - 1500110259487 Marionette TRACE 366 <- [1,6,null,{}]
02:17:39 INFO - 1500110259490 Marionette DEBUG Closed connection 366
02:17:39 INFO - 1500110259490 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49843
02:17:39 INFO - 1500110259491 Marionette DEBUG Closed connection 367
02:17:39 INFO - 1500110259491 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49844
02:17:39 INFO - 1500110259493 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:39 INFO - 1500110259493 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259496 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259500 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c3610a22-a4f7-4411-99d8-00b0e9bfa18d","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259502 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
02:17:39 INFO - 1500110259503 Marionette TRACE 368 <- [1,2,null,{}]
02:17:39 INFO - 1500110259505 Marionette DEBUG Closed connection 368
02:17:39 INFO - 1500110259506 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49845
02:17:39 INFO - 1500110259507 Marionette DEBUG Closed connection 369
02:17:39 INFO - 1500110259507 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49846
02:17:39 INFO - 1500110259508 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:17:39 INFO - 1500110259509 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259512 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259515 Marionette TRACE 370 <- [1,1,null,{"sessionId":"07d4f2cf-436a-4955-846d-34bb253c0cdc","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259517 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"}]
02:17:39 INFO - 1500110259519 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"
02:17:39 INFO - 1500110259521 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"
02:17:39 INFO - 1500110259521 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"
02:17:39 INFO - 1500110259549 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"
02:17:39 INFO - 1500110259551 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"
02:17:39 INFO - 1500110259554 Marionette TRACE 370 <- [1,2,null,{}]
02:17:39 INFO - 1500110259555 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:17:39 INFO - 1500110259565 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c73096d7-41f7-429f-bc7e-fe68a3ea0f3e","ELEMENT":"c73096d7-41f7-429f-bc7e-fe68a3ea0f3e"}}]
02:17:39 INFO - 1500110259567 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c73096d7-41f7-429f-bc7e-fe68a3ea0f3e"}]
02:17:39 INFO - 1500110259813 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:17:39 INFO - 1500110259813 Marionette TRACE 370 <- [1,4,null,{}]
02:17:39 INFO - 1500110259815 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c73096d7-41f7-429f-bc7e-fe68a3ea0f3e","name":"selected"}]
02:17:39 INFO - 1500110259819 Marionette TRACE 370 <- [1,5,null,{"value":true}]
02:17:39 INFO - 1500110259821 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}]
02:17:39 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500110259823 Marionette TRACE 370 <- [1,6,null,{"value":null}]
02:17:39 INFO - 1500110259824 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
02:17:39 INFO - 1500110259826 Marionette TRACE 370 <- [1,7,null,{}]
02:17:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 372ms
02:17:39 INFO - TEST-START | test_click.py TestClick.test_css_transforms
02:17:39 INFO - 1500110259828 Marionette DEBUG Closed connection 370
02:17:39 INFO - 1500110259829 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49847
02:17:39 INFO - 1500110259830 Marionette DEBUG Closed connection 371
02:17:39 INFO - 1500110259830 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49848
02:17:39 INFO - 1500110259831 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:39 INFO - 1500110259832 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259834 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259837 Marionette TRACE 372 <- [1,1,null,{"sessionId":"32e65ef5-1460-40ee-a713-e4ac3ed5009c","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259840 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
02:17:39 INFO - 1500110259840 Marionette TRACE 372 <- [1,2,null,{}]
02:17:39 INFO - 1500110259841 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:17:39 INFO - 1500110259841 Marionette TRACE 372 <- [1,3,null,{}]
02:17:39 INFO - 1500110259842 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
02:17:39 INFO - 1500110259843 Marionette TRACE 372 <- [1,4,null,{}]
02:17:39 INFO - 1500110259845 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}]
02:17:39 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500110259847 Marionette TRACE 372 <- [1,5,null,{"value":null}]
02:17:39 INFO - 1500110259849 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
02:17:39 INFO - 1500110259850 Marionette TRACE 372 <- [1,6,null,{}]
02:17:39 INFO - 1500110259852 Marionette DEBUG Closed connection 372
02:17:39 INFO - 1500110259853 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49849
02:17:39 INFO - 1500110259853 Marionette DEBUG Closed connection 373
02:17:39 INFO - 1500110259854 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49850
02:17:39 INFO - 1500110259855 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:39 INFO - 1500110259856 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259858 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259861 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a001c7a7-4e8d-4426-853a-5f189b5bff41","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259863 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
02:17:39 INFO - 1500110259864 Marionette TRACE 374 <- [1,2,null,{}]
02:17:39 INFO - 1500110259867 Marionette DEBUG Closed connection 374
02:17:39 INFO - 1500110259867 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49851
02:17:39 INFO - 1500110259868 Marionette DEBUG Closed connection 375
02:17:39 INFO - 1500110259868 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49852
02:17:39 INFO - 1500110259869 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:17:39 INFO - 1500110259870 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:39 INFO - 1500110259872 Marionette DEBUG Register listener.js for window 15
02:17:39 INFO - 1500110259875 Marionette TRACE 376 <- [1,1,null,{"sessionId":"c4015a94-db3e-4c83-9c2c-52543ac56707","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:39 INFO - 1500110259877 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"}]
02:17:39 INFO - 1500110259879 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"
02:17:39 INFO - 1500110259881 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"
02:17:39 INFO - 1500110259881 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"
02:17:39 INFO - 1500110259900 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"
02:17:39 INFO - 1500110259903 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"
02:17:39 INFO - 1500110259906 Marionette TRACE 376 <- [1,2,null,{}]
02:17:39 INFO - 1500110259914 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
02:17:39 INFO - 1500110259916 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79eafe2f-620e-427c-8cc7-68709614454c","ELEMENT":"79eafe2f-620e-427c-8cc7-68709614454c"}}]
02:17:39 INFO - 1500110259917 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"79eafe2f-620e-427c-8cc7-68709614454c"}]
02:17:40 INFO - 1500110260166 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:17:40 INFO - 1500110260166 Marionette TRACE 376 <- [1,4,null,{}]
02:17:40 INFO - 1500110260168 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}]
02:17:40 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500110260171 Marionette TRACE 376 <- [1,5,null,{"value":null}]
02:17:40 INFO - 1500110260172 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
02:17:40 INFO - 1500110260173 Marionette TRACE 376 <- [1,6,null,{}]
02:17:40 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 349ms
02:17:40 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
02:17:40 INFO - 1500110260176 Marionette DEBUG Closed connection 376
02:17:40 INFO - 1500110260177 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49853
02:17:40 INFO - 1500110260177 Marionette DEBUG Closed connection 377
02:17:40 INFO - 1500110260178 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49854
02:17:40 INFO - 1500110260179 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:40 INFO - 1500110260180 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260182 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260185 Marionette TRACE 378 <- [1,1,null,{"sessionId":"eb82b5c4-43fe-41b3-b7c4-ebdc0a57fd6e","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260188 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
02:17:40 INFO - 1500110260188 Marionette TRACE 378 <- [1,2,null,{}]
02:17:40 INFO - 1500110260189 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:17:40 INFO - 1500110260190 Marionette TRACE 378 <- [1,3,null,{}]
02:17:40 INFO - 1500110260190 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
02:17:40 INFO - 1500110260191 Marionette TRACE 378 <- [1,4,null,{}]
02:17:40 INFO - 1500110260193 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}]
02:17:40 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500110260195 Marionette TRACE 378 <- [1,5,null,{"value":null}]
02:17:40 INFO - 1500110260196 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
02:17:40 INFO - 1500110260197 Marionette TRACE 378 <- [1,6,null,{}]
02:17:40 INFO - 1500110260200 Marionette DEBUG Closed connection 378
02:17:40 INFO - 1500110260200 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49855
02:17:40 INFO - 1500110260201 Marionette DEBUG Closed connection 379
02:17:40 INFO - 1500110260202 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49856
02:17:40 INFO - 1500110260203 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:40 INFO - 1500110260203 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260206 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260209 Marionette TRACE 380 <- [1,1,null,{"sessionId":"71e36938-2375-4146-9a3e-b1f3e1155ab5","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260211 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
02:17:40 INFO - 1500110260212 Marionette TRACE 380 <- [1,2,null,{}]
02:17:40 INFO - 1500110260214 Marionette DEBUG Closed connection 380
02:17:40 INFO - 1500110260215 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49857
02:17:40 INFO - 1500110260216 Marionette DEBUG Closed connection 381
02:17:40 INFO - 1500110260216 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49858
02:17:40 INFO - 1500110260217 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:17:40 INFO - 1500110260218 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260220 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260223 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2db6d9db-63fa-4f8d-a945-11740c3fcd7d","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260227 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"}]
02:17:40 INFO - 1500110260228 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"
02:17:40 INFO - 1500110260230 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"
02:17:40 INFO - 1500110260231 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"
02:17:40 INFO - 1500110260250 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"
02:17:40 INFO - 1500110260252 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"
02:17:40 INFO - 1500110260256 Marionette TRACE 382 <- [1,2,null,{}]
02:17:40 INFO - 1500110260264 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
02:17:40 INFO - 1500110260266 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cbd6e0ad-44db-4c0c-94da-37f2528f6f12","ELEMENT":"cbd6e0ad-44db-4c0c-94da-37f2528f6f12"}}]
02:17:40 INFO - 1500110260268 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"cbd6e0ad-44db-4c0c-94da-37f2528f6f12"}]
02:17:40 INFO - 1500110260513 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:17:40 INFO - 1500110260514 Marionette TRACE 382 <- [1,4,null,{}]
02:17:40 INFO - 1500110260516 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}]
02:17:40 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500110260518 Marionette TRACE 382 <- [1,5,null,{"value":null}]
02:17:40 INFO - 1500110260519 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
02:17:40 INFO - 1500110260521 Marionette TRACE 382 <- [1,6,null,{}]
02:17:40 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 347ms
02:17:40 INFO - TEST-START | test_click.py TestClick.test_input_file
02:17:40 INFO - 1500110260523 Marionette DEBUG Closed connection 382
02:17:40 INFO - 1500110260524 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49859
02:17:40 INFO - 1500110260525 Marionette DEBUG Closed connection 383
02:17:40 INFO - 1500110260526 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49860
02:17:40 INFO - 1500110260527 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:40 INFO - 1500110260528 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260530 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260533 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f30104b4-2fa7-498f-a7b9-8a05334f7a12","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260535 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
02:17:40 INFO - 1500110260536 Marionette TRACE 384 <- [1,2,null,{}]
02:17:40 INFO - 1500110260537 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:17:40 INFO - 1500110260537 Marionette TRACE 384 <- [1,3,null,{}]
02:17:40 INFO - 1500110260538 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
02:17:40 INFO - 1500110260538 Marionette TRACE 384 <- [1,4,null,{}]
02:17:40 INFO - 1500110260540 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}]
02:17:40 INFO - TEST-START: test_click.py TestClick.test_input_file1500110260542 Marionette TRACE 384 <- [1,5,null,{"value":null}]
02:17:40 INFO - 1500110260544 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
02:17:40 INFO - 1500110260545 Marionette TRACE 384 <- [1,6,null,{}]
02:17:40 INFO - 1500110260547 Marionette DEBUG Closed connection 384
02:17:40 INFO - 1500110260548 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49861
02:17:40 INFO - 1500110260548 Marionette DEBUG Closed connection 385
02:17:40 INFO - 1500110260549 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49862
02:17:40 INFO - 1500110260555 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:40 INFO - 1500110260556 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260558 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260567 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d8f25f44-00f9-44a1-b5ec-75fd652767bf","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260570 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
02:17:40 INFO - 1500110260571 Marionette TRACE 386 <- [1,2,null,{}]
02:17:40 INFO - 1500110260573 Marionette DEBUG Closed connection 386
02:17:40 INFO - 1500110260574 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49863
02:17:40 INFO - 1500110260575 Marionette DEBUG Closed connection 387
02:17:40 INFO - 1500110260575 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49864
02:17:40 INFO - 1500110260577 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:17:40 INFO - 1500110260577 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260580 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260583 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4ab87780-3e3b-4cc5-8427-e9a3d8705bd4","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260585 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
02:17:40 INFO - 1500110260586 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"
02:17:40 INFO - 1500110260588 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"
02:17:40 INFO - 1500110260589 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"
02:17:40 INFO - 1500110260613 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:17:40 INFO - 1500110260615 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:17:40 INFO - 1500110260618 Marionette TRACE 388 <- [1,2,null,{}]
02:17:40 INFO - 1500110260620 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
02:17:40 INFO - 1500110260622 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e6c93de5-f78b-4430-9a2f-025cba759dfb","ELEMENT":"e6c93de5-f78b-4430-9a2f-025cba759dfb"}}]
02:17:40 INFO - 1500110260623 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"e6c93de5-f78b-4430-9a2f-025cba759dfb"}]
02:17:40 INFO - 1500110260658 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
02:17:40 INFO - 1500110260672 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}]
02:17:40 INFO - TEST-END: test_click.py TestClick.test_input_file1500110260675 Marionette TRACE 388 <- [1,5,null,{"value":null}]
02:17:40 INFO - 1500110260677 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
02:17:40 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 158ms
02:17:40 INFO - TEST-START | test_click.py TestClick.test_obscured_element
02:17:40 INFO - 1500110260680 Marionette TRACE 388 <- [1,6,null,{}]
02:17:40 INFO - 1500110260683 Marionette DEBUG Closed connection 388
02:17:40 INFO - 1500110260684 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49865
02:17:40 INFO - 1500110260685 Marionette DEBUG Closed connection 389
02:17:40 INFO - 1500110260686 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49866
02:17:40 INFO - 1500110260688 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:40 INFO - 1500110260691 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260694 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260701 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d33d7980-a7b9-4948-a1c2-d3fb3ef567f3","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260705 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
02:17:40 INFO - 1500110260706 Marionette TRACE 390 <- [1,2,null,{}]
02:17:40 INFO - 1500110260708 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:17:40 INFO - 1500110260708 Marionette TRACE 390 <- [1,3,null,{}]
02:17:40 INFO - 1500110260710 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
02:17:40 INFO - 1500110260711 Marionette TRACE 390 <- [1,4,null,{}]
02:17:40 INFO - 1500110260713 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}]
02:17:40 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500110260717 Marionette TRACE 390 <- [1,5,null,{"value":null}]
02:17:40 INFO - 1500110260718 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
02:17:40 INFO - 1500110260720 Marionette TRACE 390 <- [1,6,null,{}]
02:17:40 INFO - 1500110260724 Marionette DEBUG Closed connection 390
02:17:40 INFO - 1500110260725 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49867
02:17:40 INFO - 1500110260726 Marionette DEBUG Closed connection 391
02:17:40 INFO - 1500110260727 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49868
02:17:40 INFO - 1500110260728 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:40 INFO - 1500110260729 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260732 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260737 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a4336ca8-faef-4489-801d-dbbd33fcbb73","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260739 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
02:17:40 INFO - 1500110260741 Marionette TRACE 392 <- [1,2,null,{}]
02:17:40 INFO - 1500110260744 Marionette DEBUG Closed connection 392
02:17:40 INFO - 1500110260744 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49869
02:17:40 INFO - 1500110260746 Marionette DEBUG Closed connection 393
02:17:40 INFO - 1500110260746 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49870
02:17:40 INFO - 1500110260749 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:17:40 INFO - 1500110260751 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:40 INFO - 1500110260754 Marionette DEBUG Register listener.js for window 15
02:17:40 INFO - 1500110260759 Marionette TRACE 394 <- [1,1,null,{"sessionId":"10b9c3e8-70eb-4b0d-8380-921bccf92cf0","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:40 INFO - 1500110260761 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"}]
02:17:40 INFO - 1500110260763 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:17:40 INFO - 1500110260767 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:17:40 INFO - 1500110260767 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:17:40 INFO - 1500110260802 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"
02:17:40 INFO - 1500110260805 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"
02:17:40 INFO - 1500110260809 Marionette TRACE 394 <- [1,2,null,{}]
02:17:40 INFO - 1500110260817 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
02:17:40 INFO - 1500110260820 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d448bb6a-cab1-4d05-b6a9-4dc7a69ce613","ELEMENT":"d448bb6a-cab1-4d05-b6a9-4dc7a69ce613"}}]
02:17:40 INFO - 1500110260821 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
02:17:40 INFO - 1500110260824 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3b9ed244-1a5e-48ac-9061-c779868f1974","ELEMENT":"3b9ed244-1a5e-48ac-9061-c779868f1974"}}]
02:17:40 INFO - 1500110260838 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"d448bb6a-cab1-4d05-b6a9-4dc7a69ce613"}]
02:17:41 INFO - 1500110261143 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:17:41 INFO - 1500110261144 Marionette TRACE 394 <- [1,5,null,{}]
02:17:41 INFO - 1500110261146 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"3b9ed244-1a5e-48ac-9061-c779868f1974"}]
02:17:41 INFO - 1500110261166 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
02:17:41 INFO - 1500110261168 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
02:17:41 INFO - 1500110261173 Marionette TRACE 394 <- [1,7,null,{"value":false}]
02:17:41 INFO - 1500110261175 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}]
02:17:41 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500110261179 Marionette TRACE 394 <- [1,8,null,{"value":null}]
02:17:41 INFO - 1500110261180 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
02:17:41 INFO - 1500110261182 Marionette TRACE 394 <- [1,9,null,{}]
02:17:41 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 503ms
02:17:41 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
02:17:41 INFO - 1500110261186 Marionette DEBUG Closed connection 394
02:17:41 INFO - 1500110261187 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49871
02:17:41 INFO - 1500110261189 Marionette DEBUG Closed connection 395
02:17:41 INFO - 1500110261190 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49872
02:17:41 INFO - 1500110261191 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:41 INFO - 1500110261194 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:41 INFO - 1500110261197 Marionette DEBUG Register listener.js for window 15
02:17:41 INFO - 1500110261203 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ef24cb4f-b146-47da-96b5-cce43c93b9c5","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:41 INFO - 1500110261207 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
02:17:41 INFO - 1500110261208 Marionette TRACE 396 <- [1,2,null,{}]
02:17:41 INFO - 1500110261209 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:17:41 INFO - 1500110261210 Marionette TRACE 396 <- [1,3,null,{}]
02:17:41 INFO - 1500110261211 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
02:17:41 INFO - 1500110261212 Marionette TRACE 396 <- [1,4,null,{}]
02:17:41 INFO - 1500110261214 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}]
02:17:41 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500110261218 Marionette TRACE 396 <- [1,5,null,{"value":null}]
02:17:41 INFO - 1500110261219 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
02:17:41 INFO - 1500110261221 Marionette TRACE 396 <- [1,6,null,{}]
02:17:41 INFO - 1500110261225 Marionette DEBUG Closed connection 396
02:17:41 INFO - 1500110261226 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49873
02:17:41 INFO - 1500110261227 Marionette DEBUG Closed connection 397
02:17:41 INFO - 1500110261227 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49874
02:17:41 INFO - 1500110261230 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:17:41 INFO - 1500110261232 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:41 INFO - 1500110261235 Marionette DEBUG Register listener.js for window 15
02:17:41 INFO - 1500110261239 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a5087340-50e8-4917-89fb-96d31ed811bf","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:41 INFO - 1500110261242 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
02:17:41 INFO - 1500110261244 Marionette TRACE 398 <- [1,2,null,{}]
02:17:41 INFO - 1500110261246 Marionette DEBUG Closed connection 398
02:17:41 INFO - 1500110261247 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49875
02:17:41 INFO - 1500110261248 Marionette DEBUG Closed connection 399
02:17:41 INFO - 1500110261249 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49876
02:17:41 INFO - 1500110261250 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:17:41 INFO - 1500110261251 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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}
02:17:41 INFO - 1500110261254 Marionette DEBUG Register listener.js for window 15
02:17:41 INFO - 1500110261259 Marionette TRACE 400 <- [1,1,null,{"sessionId":"7ca112ed-4d58-4d93-baf9-fe663003fddd","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":320,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmptu4jc4.mozrunner","moz:accessibilityChecks":false}}]
02:17:41 INFO - 1500110261262 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"}]
02:17:41 INFO - 1500110261265 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"
02:17:41 INFO - 1500110261279 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"
02:17:41 INFO - 1500110261279 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"
02:17:41 INFO - 1500110261302 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"
02:17:41 INFO - 1500110261305 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"
02:17:41 INFO - 1500110261315 Marionette TRACE 400 <- [1,2,null,{}]
02:17:41 INFO - 1500110261319 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
02:17:41 INFO - 1500110261322 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d93a6651-378d-419a-89e2-7f47434e4702","ELEMENT":"d93a6651-378d-419a-89e2-7f47434e4702"}}]
02:17:41 INFO - 1500110261323 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"d93a6651-378d-419a-89e2-7f47434e4702"}]
02:17:41 INFO - 1500110261349 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
02:17:41 INFO - 1500110261351 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"d93a6651-378d-419a-89e2-7f47434e4702"}]
02:17:41 INFO - 1500110261369 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element