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]
08:41:17 INFO - 1500478877034 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}]
08:41:17 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500478877037 Marionette TRACE 352 <- [1,5,null,{"value":null}]
08:41:17 INFO - 1500478877038 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
08:41:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 153ms
08:41:17 INFO - 1500478877040 Marionette TRACE 352 <- [1,6,null,{}]
08:41:17 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
08:41:17 INFO - 1500478877042 Marionette DEBUG Closed connection 352
08:41:17 INFO - 1500478877043 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49830
08:41:17 INFO - 1500478877044 Marionette DEBUG Closed connection 353
08:41:17 INFO - 1500478877045 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49831
08:41:17 INFO - 1500478877046 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:17 INFO - 1500478877046 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877049 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877052 Marionette TRACE 354 <- [1,1,null,{"sessionId":"68f875d2-8bb5-4220-8ea5-b35d0fe91de7","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877054 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
08:41:17 INFO - 1500478877055 Marionette TRACE 354 <- [1,2,null,{}]
08:41:17 INFO - 1500478877056 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:41:17 INFO - 1500478877056 Marionette TRACE 354 <- [1,3,null,{}]
08:41:17 INFO - 1500478877057 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
08:41:17 INFO - 1500478877057 Marionette TRACE 354 <- [1,4,null,{}]
08:41:17 INFO - 1500478877059 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}]
08:41:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500478877062 Marionette TRACE 354 <- [1,5,null,{"value":null}]
08:41:17 INFO - 1500478877062 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
08:41:17 INFO - 1500478877064 Marionette TRACE 354 <- [1,6,null,{}]
08:41:17 INFO - 1500478877066 Marionette DEBUG Closed connection 354
08:41:17 INFO - 1500478877067 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49832
08:41:17 INFO - 1500478877068 Marionette DEBUG Closed connection 355
08:41:17 INFO - 1500478877068 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49833
08:41:17 INFO - 1500478877069 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:17 INFO - 1500478877070 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877072 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877076 Marionette TRACE 356 <- [1,1,null,{"sessionId":"cea8c5bd-14c9-4c17-b9ee-64d7891db732","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877078 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
08:41:17 INFO - 1500478877079 Marionette TRACE 356 <- [1,2,null,{}]
08:41:17 INFO - 1500478877081 Marionette DEBUG Closed connection 356
08:41:17 INFO - 1500478877082 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49834
08:41:17 INFO - 1500478877083 Marionette DEBUG Closed connection 357
08:41:17 INFO - 1500478877083 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49835
08:41:17 INFO - 1500478877084 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:41:17 INFO - 1500478877085 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877088 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877091 Marionette TRACE 358 <- [1,1,null,{"sessionId":"baf24e01-dfe9-49e6-822e-2387eeff020a","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877093 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49403/clicks.html"}]
08:41:17 INFO - 1500478877095 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49403/hidden.html"
08:41:17 INFO - 1500478877099 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49403/hidden.html"
08:41:17 INFO - 1500478877099 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49403/hidden.html"
08:41:17 INFO - 1500478877117 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49403/clicks.html"
08:41:17 INFO - 1500478877122 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49403/clicks.html"
08:41:17 INFO - 1500478877131 Marionette TRACE 358 <- [1,2,null,{}]
08:41:17 INFO - 1500478877133 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
08:41:17 INFO - 1500478877135 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"22584e2e-1bf8-4d13-bbbe-fabaa1978080","ELEMENT":"22584e2e-1bf8-4d13-bbbe-fabaa1978080"}}]
08:41:17 INFO - 1500478877136 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"22584e2e-1bf8-4d13-bbbe-fabaa1978080"}]
08:41:17 INFO - 1500478877186 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49403/clicks.html"
08:41:17 INFO - 1500478877195 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49403/clicks.html"
08:41:17 INFO - 1500478877196 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49403/clicks.html"
08:41:17 INFO - 1500478877216 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49403/test.html"
08:41:17 INFO - 1500478877218 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49403/test.html"
08:41:17 INFO - 1500478877224 Marionette TRACE 358 <- [1,4,null,{}]
08:41:17 INFO - 1500478877231 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
08:41:17 INFO - 1500478877234 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"71336406-fa37-4d2d-ac72-6d02771cb681","ELEMENT":"71336406-fa37-4d2d-ac72-6d02771cb681"}}]
08:41:17 INFO - 1500478877235 Marionette TRACE 358 -> [0,6,"getTitle",{}]
08:41:17 INFO - 1500478877235 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
08:41:17 INFO - 1500478877237 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}]
08:41:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500478877240 Marionette TRACE 358 <- [1,7,null,{"value":null}]
08:41:17 INFO - 1500478877242 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
08:41:17 INFO - 1500478877244 Marionette TRACE 358 <- [1,8,null,{}]
08:41:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 204ms
08:41:17 INFO - TEST-START | test_click.py TestClick.test_container_element
08:41:17 INFO - 1500478877246 Marionette DEBUG Closed connection 358
08:41:17 INFO - 1500478877247 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49838
08:41:17 INFO - 1500478877247 Marionette DEBUG Closed connection 359
08:41:17 INFO - 1500478877248 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49839
08:41:17 INFO - 1500478877250 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:17 INFO - 1500478877251 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877253 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877257 Marionette TRACE 360 <- [1,1,null,{"sessionId":"1d8ae219-9c12-49c8-8e07-c12277fae3f6","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877270 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
08:41:17 INFO - 1500478877270 Marionette TRACE 360 <- [1,2,null,{}]
08:41:17 INFO - 1500478877271 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:41:17 INFO - 1500478877272 Marionette TRACE 360 <- [1,3,null,{}]
08:41:17 INFO - 1500478877273 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
08:41:17 INFO - 1500478877273 Marionette TRACE 360 <- [1,4,null,{}]
08:41:17 INFO - 1500478877275 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}]
08:41:17 INFO - TEST-START: test_click.py TestClick.test_container_element1500478877277 Marionette TRACE 360 <- [1,5,null,{"value":null}]
08:41:17 INFO - 1500478877278 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
08:41:17 INFO - 1500478877280 Marionette TRACE 360 <- [1,6,null,{}]
08:41:17 INFO - 1500478877282 Marionette DEBUG Closed connection 360
08:41:17 INFO - 1500478877283 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49840
08:41:17 INFO - 1500478877283 Marionette DEBUG Closed connection 361
08:41:17 INFO - 1500478877284 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49841
08:41:17 INFO - 1500478877285 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:17 INFO - 1500478877286 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877289 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877293 Marionette TRACE 362 <- [1,1,null,{"sessionId":"37fea278-1510-4e7c-9d76-7c821bb81d8f","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877295 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
08:41:17 INFO - 1500478877296 Marionette TRACE 362 <- [1,2,null,{}]
08:41:17 INFO - 1500478877298 Marionette DEBUG Closed connection 362
08:41:17 INFO - 1500478877299 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49842
08:41:17 INFO - 1500478877300 Marionette DEBUG Closed connection 363
08:41:17 INFO - 1500478877300 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49843
08:41:17 INFO - 1500478877301 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:41:17 INFO - 1500478877302 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877305 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877308 Marionette TRACE 364 <- [1,1,null,{"sessionId":"66fdcd81-fe20-432b-9343-71af1da96528","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877310 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"}]
08:41:17 INFO - 1500478877311 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49403/test.html"
08:41:17 INFO - 1500478877313 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49403/test.html"
08:41:17 INFO - 1500478877313 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49403/test.html"
08:41:17 INFO - 1500478877337 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"
08:41:17 INFO - 1500478877341 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"
08:41:17 INFO - 1500478877349 Marionette TRACE 364 <- [1,2,null,{}]
08:41:17 INFO - 1500478877350 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:41:17 INFO - 1500478877353 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0118f857-4f6c-43b8-a197-7331aa98ab3b","ELEMENT":"0118f857-4f6c-43b8-a197-7331aa98ab3b"}}]
08:41:17 INFO - 1500478877354 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0118f857-4f6c-43b8-a197-7331aa98ab3b"}]
08:41:17 INFO - 1500478877604 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:41:17 INFO - 1500478877605 Marionette TRACE 364 <- [1,4,null,{}]
08:41:17 INFO - 1500478877606 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0118f857-4f6c-43b8-a197-7331aa98ab3b","name":"selected"}]
08:41:17 INFO - 1500478877611 Marionette TRACE 364 <- [1,5,null,{"value":true}]
08:41:17 INFO - 1500478877612 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}]
08:41:17 INFO - TEST-END: test_click.py TestClick.test_container_element1500478877615 Marionette TRACE 364 <- [1,6,null,{"value":null}]
08:41:17 INFO - 1500478877616 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
08:41:17 INFO - 1500478877617 Marionette TRACE 364 <- [1,7,null,{}]
08:41:17 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 373ms
08:41:17 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
08:41:17 INFO - 1500478877619 Marionette DEBUG Closed connection 364
08:41:17 INFO - 1500478877620 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49844
08:41:17 INFO - 1500478877622 Marionette DEBUG Closed connection 365
08:41:17 INFO - 1500478877623 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49845
08:41:17 INFO - 1500478877624 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:17 INFO - 1500478877625 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877627 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877631 Marionette TRACE 366 <- [1,1,null,{"sessionId":"61afcffb-d179-4960-a74f-adbc143ea390","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877633 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
08:41:17 INFO - 1500478877633 Marionette TRACE 366 <- [1,2,null,{}]
08:41:17 INFO - 1500478877634 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:41:17 INFO - 1500478877635 Marionette TRACE 366 <- [1,3,null,{}]
08:41:17 INFO - 1500478877636 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
08:41:17 INFO - 1500478877637 Marionette TRACE 366 <- [1,4,null,{}]
08:41:17 INFO - 1500478877638 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}]
08:41:17 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500478877641 Marionette TRACE 366 <- [1,5,null,{"value":null}]
08:41:17 INFO - 1500478877642 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
08:41:17 INFO - 1500478877643 Marionette TRACE 366 <- [1,6,null,{}]
08:41:17 INFO - 1500478877646 Marionette DEBUG Closed connection 366
08:41:17 INFO - 1500478877646 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49846
08:41:17 INFO - 1500478877647 Marionette DEBUG Closed connection 367
08:41:17 INFO - 1500478877648 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49847
08:41:17 INFO - 1500478877649 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:17 INFO - 1500478877650 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877653 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877664 Marionette TRACE 368 <- [1,1,null,{"sessionId":"3a51ce69-b9f8-46ec-829c-dec6fe78fa3d","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877666 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
08:41:17 INFO - 1500478877668 Marionette TRACE 368 <- [1,2,null,{}]
08:41:17 INFO - 1500478877670 Marionette DEBUG Closed connection 368
08:41:17 INFO - 1500478877670 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49848
08:41:17 INFO - 1500478877671 Marionette DEBUG Closed connection 369
08:41:17 INFO - 1500478877672 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49849
08:41:17 INFO - 1500478877673 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:41:17 INFO - 1500478877674 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877676 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877679 Marionette TRACE 370 <- [1,1,null,{"sessionId":"84f8cb4e-d777-4a44-a70a-753f64c1fd44","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877681 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"}]
08:41:17 INFO - 1500478877683 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"
08:41:17 INFO - 1500478877685 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"
08:41:17 INFO - 1500478877685 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"
08:41:17 INFO - 1500478877703 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"
08:41:17 INFO - 1500478877706 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"
08:41:17 INFO - 1500478877716 Marionette TRACE 370 <- [1,2,null,{}]
08:41:17 INFO - 1500478877718 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:41:17 INFO - 1500478877720 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"35a7747c-d42a-497e-8e26-0b7b2eb95272","ELEMENT":"35a7747c-d42a-497e-8e26-0b7b2eb95272"}}]
08:41:17 INFO - 1500478877721 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"35a7747c-d42a-497e-8e26-0b7b2eb95272"}]
08:41:17 INFO - 1500478877970 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:41:17 INFO - 1500478877971 Marionette TRACE 370 <- [1,4,null,{}]
08:41:17 INFO - 1500478877972 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"35a7747c-d42a-497e-8e26-0b7b2eb95272","name":"selected"}]
08:41:17 INFO - 1500478877976 Marionette TRACE 370 <- [1,5,null,{"value":true}]
08:41:17 INFO - 1500478877978 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}]
08:41:17 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500478877980 Marionette TRACE 370 <- [1,6,null,{"value":null}]
08:41:17 INFO - 1500478877981 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
08:41:17 INFO - 1500478877982 Marionette TRACE 370 <- [1,7,null,{}]
08:41:17 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 364ms
08:41:17 INFO - TEST-START | test_click.py TestClick.test_css_transforms
08:41:17 INFO - 1500478877985 Marionette DEBUG Closed connection 370
08:41:17 INFO - 1500478877985 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49850
08:41:17 INFO - 1500478877986 Marionette DEBUG Closed connection 371
08:41:17 INFO - 1500478877987 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49851
08:41:17 INFO - 1500478877988 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:17 INFO - 1500478877988 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:17 INFO - 1500478877991 Marionette DEBUG Register listener.js for window 15
08:41:17 INFO - 1500478877994 Marionette TRACE 372 <- [1,1,null,{"sessionId":"dc34a384-019b-43fb-a3a1-056008c3cf5f","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:17 INFO - 1500478877996 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
08:41:17 INFO - 1500478877996 Marionette TRACE 372 <- [1,2,null,{}]
08:41:17 INFO - 1500478877997 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:41:17 INFO - 1500478877998 Marionette TRACE 372 <- [1,3,null,{}]
08:41:18 INFO - 1500478877999 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
08:41:18 INFO - 1500478877999 Marionette TRACE 372 <- [1,4,null,{}]
08:41:18 INFO - 1500478878001 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}]
08:41:18 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500478878004 Marionette TRACE 372 <- [1,5,null,{"value":null}]
08:41:18 INFO - 1500478878005 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
08:41:18 INFO - 1500478878006 Marionette TRACE 372 <- [1,6,null,{}]
08:41:18 INFO - 1500478878008 Marionette DEBUG Closed connection 372
08:41:18 INFO - 1500478878009 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49852
08:41:18 INFO - 1500478878010 Marionette DEBUG Closed connection 373
08:41:18 INFO - 1500478878010 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49853
08:41:18 INFO - 1500478878011 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:18 INFO - 1500478878012 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878014 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878017 Marionette TRACE 374 <- [1,1,null,{"sessionId":"5fae1081-144a-48f5-96d7-e410f61ebcc8","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878019 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
08:41:18 INFO - 1500478878021 Marionette TRACE 374 <- [1,2,null,{}]
08:41:18 INFO - 1500478878023 Marionette DEBUG Closed connection 374
08:41:18 INFO - 1500478878023 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49854
08:41:18 INFO - 1500478878024 Marionette DEBUG Closed connection 375
08:41:18 INFO - 1500478878025 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49855
08:41:18 INFO - 1500478878026 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:41:18 INFO - 1500478878026 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878029 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878032 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a76a90b7-0c09-4ad9-93ea-a068aa67b531","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878034 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"}]
08:41:18 INFO - 1500478878035 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"
08:41:18 INFO - 1500478878037 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"
08:41:18 INFO - 1500478878037 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"
08:41:18 INFO - 1500478878059 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"
08:41:18 INFO - 1500478878061 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"
08:41:18 INFO - 1500478878065 Marionette TRACE 376 <- [1,2,null,{}]
08:41:18 INFO - 1500478878066 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
08:41:18 INFO - 1500478878069 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5f03b08d-e460-49ef-bc65-fdb5b095fcf2","ELEMENT":"5f03b08d-e460-49ef-bc65-fdb5b095fcf2"}}]
08:41:18 INFO - 1500478878070 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"5f03b08d-e460-49ef-bc65-fdb5b095fcf2"}]
08:41:18 INFO - 1500478878320 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:41:18 INFO - 1500478878321 Marionette TRACE 376 <- [1,4,null,{}]
08:41:18 INFO - 1500478878323 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}]
08:41:18 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500478878325 Marionette TRACE 376 <- [1,5,null,{"value":null}]
08:41:18 INFO - 1500478878326 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
08:41:18 INFO - 1500478878328 Marionette TRACE 376 <- [1,6,null,{}]
08:41:18 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 344ms
08:41:18 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
08:41:18 INFO - 1500478878330 Marionette DEBUG Closed connection 376
08:41:18 INFO - 1500478878330 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49856
08:41:18 INFO - 1500478878331 Marionette DEBUG Closed connection 377
08:41:18 INFO - 1500478878332 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49857
08:41:18 INFO - 1500478878333 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:18 INFO - 1500478878334 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878336 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878340 Marionette TRACE 378 <- [1,1,null,{"sessionId":"92605a3a-36d6-4ce2-9ac0-c314f1763003","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878342 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
08:41:18 INFO - 1500478878342 Marionette TRACE 378 <- [1,2,null,{}]
08:41:18 INFO - 1500478878343 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:41:18 INFO - 1500478878344 Marionette TRACE 378 <- [1,3,null,{}]
08:41:18 INFO - 1500478878344 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
08:41:18 INFO - 1500478878345 Marionette TRACE 378 <- [1,4,null,{}]
08:41:18 INFO - 1500478878347 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}]
08:41:18 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500478878349 Marionette TRACE 378 <- [1,5,null,{"value":null}]
08:41:18 INFO - 1500478878350 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
08:41:18 INFO - 1500478878351 Marionette TRACE 378 <- [1,6,null,{}]
08:41:18 INFO - 1500478878353 Marionette DEBUG Closed connection 378
08:41:18 INFO - 1500478878354 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49858
08:41:18 INFO - 1500478878355 Marionette DEBUG Closed connection 379
08:41:18 INFO - 1500478878355 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49859
08:41:18 INFO - 1500478878357 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:18 INFO - 1500478878357 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878360 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878363 Marionette TRACE 380 <- [1,1,null,{"sessionId":"4aaeb0a2-f6dc-4397-8a00-4c1e1cea1db8","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878365 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
08:41:18 INFO - 1500478878366 Marionette TRACE 380 <- [1,2,null,{}]
08:41:18 INFO - 1500478878368 Marionette DEBUG Closed connection 380
08:41:18 INFO - 1500478878369 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49860
08:41:18 INFO - 1500478878370 Marionette DEBUG Closed connection 381
08:41:18 INFO - 1500478878370 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49861
08:41:18 INFO - 1500478878372 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:41:18 INFO - 1500478878372 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878375 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878378 Marionette TRACE 382 <- [1,1,null,{"sessionId":"dc7b0166-f9b3-42cc-a201-0a51bc3db86c","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878381 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"}]
08:41:18 INFO - 1500478878382 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"
08:41:18 INFO - 1500478878384 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"
08:41:18 INFO - 1500478878384 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"
08:41:18 INFO - 1500478878404 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"
08:41:18 INFO - 1500478878408 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"
08:41:18 INFO - 1500478878412 Marionette TRACE 382 <- [1,2,null,{}]
08:41:18 INFO - 1500478878414 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
08:41:18 INFO - 1500478878416 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f33550c-b59e-41ee-8a2a-42b5fc56554d","ELEMENT":"9f33550c-b59e-41ee-8a2a-42b5fc56554d"}}]
08:41:18 INFO - 1500478878417 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"9f33550c-b59e-41ee-8a2a-42b5fc56554d"}]
08:41:18 INFO - 1500478878672 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:41:18 INFO - 1500478878673 Marionette TRACE 382 <- [1,4,null,{}]
08:41:18 INFO - 1500478878675 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}]
08:41:18 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500478878677 Marionette TRACE 382 <- [1,5,null,{"value":null}]
08:41:18 INFO - 1500478878678 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
08:41:18 INFO - 1500478878680 Marionette TRACE 382 <- [1,6,null,{}]
08:41:18 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 351ms
08:41:18 INFO - TEST-START | test_click.py TestClick.test_input_file
08:41:18 INFO - 1500478878682 Marionette DEBUG Closed connection 382
08:41:18 INFO - 1500478878682 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49862
08:41:18 INFO - 1500478878683 Marionette DEBUG Closed connection 383
08:41:18 INFO - 1500478878684 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49863
08:41:18 INFO - 1500478878685 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:18 INFO - 1500478878685 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878688 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878691 Marionette TRACE 384 <- [1,1,null,{"sessionId":"091fc0ea-09a2-491f-8012-5db3acd41942","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878693 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
08:41:18 INFO - 1500478878694 Marionette TRACE 384 <- [1,2,null,{}]
08:41:18 INFO - 1500478878695 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:41:18 INFO - 1500478878695 Marionette TRACE 384 <- [1,3,null,{}]
08:41:18 INFO - 1500478878696 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
08:41:18 INFO - 1500478878697 Marionette TRACE 384 <- [1,4,null,{}]
08:41:18 INFO - 1500478878698 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}]
08:41:18 INFO - TEST-START: test_click.py TestClick.test_input_file1500478878701 Marionette TRACE 384 <- [1,5,null,{"value":null}]
08:41:18 INFO - 1500478878702 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
08:41:18 INFO - 1500478878703 Marionette TRACE 384 <- [1,6,null,{}]
08:41:18 INFO - 1500478878705 Marionette DEBUG Closed connection 384
08:41:18 INFO - 1500478878706 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49864
08:41:18 INFO - 1500478878707 Marionette DEBUG Closed connection 385
08:41:18 INFO - 1500478878707 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49865
08:41:18 INFO - 1500478878708 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:18 INFO - 1500478878709 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878711 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878714 Marionette TRACE 386 <- [1,1,null,{"sessionId":"16d0f52c-5f06-41e0-843b-788ad00fd003","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878716 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
08:41:18 INFO - 1500478878718 Marionette TRACE 386 <- [1,2,null,{}]
08:41:18 INFO - 1500478878719 Marionette DEBUG Closed connection 386
08:41:18 INFO - 1500478878720 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49866
08:41:18 INFO - 1500478878721 Marionette DEBUG Closed connection 387
08:41:18 INFO - 1500478878721 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49867
08:41:18 INFO - 1500478878722 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:41:18 INFO - 1500478878723 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878725 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878735 Marionette TRACE 388 <- [1,1,null,{"sessionId":"98f77955-2a2c-4946-ace8-873ca66fd963","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878737 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
08:41:18 INFO - 1500478878738 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"
08:41:18 INFO - 1500478878740 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"
08:41:18 INFO - 1500478878741 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"
08:41:18 INFO - 1500478878751 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:41:18 INFO - 1500478878756 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:41:18 INFO - 1500478878765 Marionette TRACE 388 <- [1,2,null,{}]
08:41:18 INFO - 1500478878771 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
08:41:18 INFO - 1500478878774 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"16b5d6c4-ec19-47b3-8914-a8981d3131b2","ELEMENT":"16b5d6c4-ec19-47b3-8914-a8981d3131b2"}}]
08:41:18 INFO - 1500478878776 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"16b5d6c4-ec19-47b3-8914-a8981d3131b2"}]
08:41:18 INFO - 1500478878804 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]
08:41:18 INFO - 1500478878806 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}]
08:41:18 INFO - TEST-END: test_click.py TestClick.test_input_file1500478878808 Marionette TRACE 388 <- [1,5,null,{"value":null}]
08:41:18 INFO - 1500478878809 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
08:41:18 INFO - 1500478878811 Marionette TRACE 388 <- [1,6,null,{}]
08:41:18 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 131ms
08:41:18 INFO - TEST-START | test_click.py TestClick.test_obscured_element
08:41:18 INFO - 1500478878813 Marionette DEBUG Closed connection 388
08:41:18 INFO - 1500478878814 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49868
08:41:18 INFO - 1500478878815 Marionette DEBUG Closed connection 389
08:41:18 INFO - 1500478878815 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49869
08:41:18 INFO - 1500478878816 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:18 INFO - 1500478878817 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878820 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878823 Marionette TRACE 390 <- [1,1,null,{"sessionId":"cbee186f-309a-4dee-b967-047d4223a6e1","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878825 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
08:41:18 INFO - 1500478878825 Marionette TRACE 390 <- [1,2,null,{}]
08:41:18 INFO - 1500478878826 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:41:18 INFO - 1500478878827 Marionette TRACE 390 <- [1,3,null,{}]
08:41:18 INFO - 1500478878828 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
08:41:18 INFO - 1500478878828 Marionette TRACE 390 <- [1,4,null,{}]
08:41:18 INFO - 1500478878830 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}]
08:41:18 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500478878833 Marionette TRACE 390 <- [1,5,null,{"value":null}]
08:41:18 INFO - 1500478878834 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
08:41:18 INFO - 1500478878835 Marionette TRACE 390 <- [1,6,null,{}]
08:41:18 INFO - 1500478878838 Marionette DEBUG Closed connection 390
08:41:18 INFO - 1500478878839 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49870
08:41:18 INFO - 1500478878840 Marionette DEBUG Closed connection 391
08:41:18 INFO - 1500478878841 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49871
08:41:18 INFO - 1500478878842 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:18 INFO - 1500478878842 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878845 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878848 Marionette TRACE 392 <- [1,1,null,{"sessionId":"bd6fd85f-a356-45fb-b73f-3ca596d4ace3","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878850 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
08:41:18 INFO - 1500478878852 Marionette TRACE 392 <- [1,2,null,{}]
08:41:18 INFO - 1500478878854 Marionette DEBUG Closed connection 392
08:41:18 INFO - 1500478878855 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49872
08:41:18 INFO - 1500478878856 Marionette DEBUG Closed connection 393
08:41:18 INFO - 1500478878856 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49873
08:41:18 INFO - 1500478878857 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:41:18 INFO - 1500478878858 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:18 INFO - 1500478878861 Marionette DEBUG Register listener.js for window 15
08:41:18 INFO - 1500478878864 Marionette TRACE 394 <- [1,1,null,{"sessionId":"69bfbd04-fed1-4757-a15b-72a54a2d94ed","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:18 INFO - 1500478878866 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"}]
08:41:18 INFO - 1500478878867 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:41:18 INFO - 1500478878869 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:41:18 INFO - 1500478878870 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:41:18 INFO - 1500478878891 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"
08:41:18 INFO - 1500478878892 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"
08:41:18 INFO - 1500478878896 Marionette TRACE 394 <- [1,2,null,{}]
08:41:18 INFO - 1500478878897 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
08:41:18 INFO - 1500478878899 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e8edfe9-b45d-40b5-b71b-7e8313c5b750","ELEMENT":"9e8edfe9-b45d-40b5-b71b-7e8313c5b750"}}]
08:41:18 INFO - 1500478878900 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
08:41:18 INFO - 1500478878902 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c2823a2c-aa2f-4168-8511-b33a459521fc","ELEMENT":"c2823a2c-aa2f-4168-8511-b33a459521fc"}}]
08:41:18 INFO - 1500478878903 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9e8edfe9-b45d-40b5-b71b-7e8313c5b750"}]
08:41:19 INFO - 1500478879210 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:41:19 INFO - 1500478879211 Marionette TRACE 394 <- [1,5,null,{}]
08:41:19 INFO - 1500478879213 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c2823a2c-aa2f-4168-8511-b33a459521fc"}]
08:41:19 INFO - 1500478879236 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]
08:41:19 INFO - 1500478879238 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
08:41:19 INFO - 1500478879242 Marionette TRACE 394 <- [1,7,null,{"value":false}]
08:41:19 INFO - 1500478879245 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}]
08:41:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500478879249 Marionette TRACE 394 <- [1,8,null,{"value":null}]
08:41:19 INFO - 1500478879250 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
08:41:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 442ms
08:41:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
08:41:19 INFO - 1500478879253 Marionette TRACE 394 <- [1,9,null,{}]
08:41:19 INFO - 1500478879257 Marionette DEBUG Closed connection 394
08:41:19 INFO - 1500478879258 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49874
08:41:19 INFO - 1500478879270 Marionette DEBUG Closed connection 395
08:41:19 INFO - 1500478879271 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49875
08:41:19 INFO - 1500478879273 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:19 INFO - 1500478879276 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:19 INFO - 1500478879280 Marionette DEBUG Register listener.js for window 15
08:41:19 INFO - 1500478879287 Marionette TRACE 396 <- [1,1,null,{"sessionId":"50815d29-1003-424b-89eb-442a80e0814d","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:19 INFO - 1500478879291 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
08:41:19 INFO - 1500478879292 Marionette TRACE 396 <- [1,2,null,{}]
08:41:19 INFO - 1500478879293 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:41:19 INFO - 1500478879294 Marionette TRACE 396 <- [1,3,null,{}]
08:41:19 INFO - 1500478879295 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
08:41:19 INFO - 1500478879296 Marionette TRACE 396 <- [1,4,null,{}]
08:41:19 INFO - 1500478879298 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}]
08:41:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500478879302 Marionette TRACE 396 <- [1,5,null,{"value":null}]
08:41:19 INFO - 1500478879303 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
08:41:19 INFO - 1500478879306 Marionette TRACE 396 <- [1,6,null,{}]
08:41:19 INFO - 1500478879311 Marionette DEBUG Closed connection 396
08:41:19 INFO - 1500478879312 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49876
08:41:19 INFO - 1500478879313 Marionette DEBUG Closed connection 397
08:41:19 INFO - 1500478879314 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49877
08:41:19 INFO - 1500478879316 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:41:19 INFO - 1500478879318 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:19 INFO - 1500478879321 Marionette DEBUG Register listener.js for window 15
08:41:19 INFO - 1500478879327 Marionette TRACE 398 <- [1,1,null,{"sessionId":"2069c456-aca6-467e-8e4a-6771e7ccb101","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:19 INFO - 1500478879330 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
08:41:19 INFO - 1500478879332 Marionette TRACE 398 <- [1,2,null,{}]
08:41:19 INFO - 1500478879335 Marionette DEBUG Closed connection 398
08:41:19 INFO - 1500478879335 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49878
08:41:19 INFO - 1500478879337 Marionette DEBUG Closed connection 399
08:41:19 INFO - 1500478879338 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49879
08:41:19 INFO - 1500478879339 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:41:19 INFO - 1500478879341 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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}
08:41:19 INFO - 1500478879343 Marionette DEBUG Register listener.js for window 15
08:41:19 INFO - 1500478879348 Marionette TRACE 400 <- [1,1,null,{"sessionId":"9461f0bf-58ec-4af8-b68e-7302b75c579a","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":336,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-221.000\\AppData\\Local\\Temp\\tmprizx1d.mozrunner","moz:accessibilityChecks":false}}]
08:41:19 INFO - 1500478879351 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"}]
08:41:19 INFO - 1500478879353 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"
08:41:19 INFO - 1500478879356 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"
08:41:19 INFO - 1500478879356 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"
08:41:19 INFO - 1500478879382 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"
08:41:19 INFO - 1500478879384 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"
08:41:19 INFO - 1500478879389 Marionette TRACE 400 <- [1,2,null,{}]
08:41:19 INFO - 1500478879406 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
08:41:19 INFO - 1500478879410 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"82065377-35e3-4e44-a5bd-1e2862292af9","ELEMENT":"82065377-35e3-4e44-a5bd-1e2862292af9"}}]
08:41:19 INFO - 1500478879411 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"82065377-35e3-4e44-a5bd-1e2862292af9"}]
08:41:19 INFO - 1500478879439 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
08:41:19 INFO - 1500478879440 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"82065377-35e3-4e44-a5bd-1e2862292af9"}]
08:41:19 INFO - 1500478879464 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element