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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:06:46 INFO - 1500977206139 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}]
03:06:46 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500977206142 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:06:46 INFO - 1500977206143 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:06:46 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 138ms
03:06:46 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:06:46 INFO - 1500977206145 Marionette TRACE 352 <- [1,6,null,{}]
03:06:46 INFO - 1500977206146 Marionette DEBUG Closed connection 352
03:06:46 INFO - 1500977206147 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49792
03:06:46 INFO - 1500977206148 Marionette DEBUG Closed connection 353
03:06:46 INFO - 1500977206149 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49793
03:06:46 INFO - 1500977206150 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:46 INFO - 1500977206151 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206154 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206158 Marionette TRACE 354 <- [1,1,null,{"sessionId":"34dfdf24-b684-44e1-9e4b-41eef68a6664","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206159 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:06:46 INFO - 1500977206159 Marionette TRACE 354 <- [1,2,null,{}]
03:06:46 INFO - 1500977206160 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:06:46 INFO - 1500977206161 Marionette TRACE 354 <- [1,3,null,{}]
03:06:46 INFO - 1500977206162 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:06:46 INFO - 1500977206162 Marionette TRACE 354 <- [1,4,null,{}]
03:06:46 INFO - 1500977206164 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}]
03:06:46 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500977206167 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:06:46 INFO - 1500977206168 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:06:46 INFO - 1500977206169 Marionette TRACE 354 <- [1,6,null,{}]
03:06:46 INFO - 1500977206171 Marionette DEBUG Closed connection 354
03:06:46 INFO - 1500977206172 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49794
03:06:46 INFO - 1500977206173 Marionette DEBUG Closed connection 355
03:06:46 INFO - 1500977206173 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49795
03:06:46 INFO - 1500977206174 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:46 INFO - 1500977206175 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206178 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206181 Marionette TRACE 356 <- [1,1,null,{"sessionId":"10399f24-d8ab-4462-beda-35201448552a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206183 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:06:46 INFO - 1500977206184 Marionette TRACE 356 <- [1,2,null,{}]
03:06:46 INFO - 1500977206186 Marionette DEBUG Closed connection 356
03:06:46 INFO - 1500977206187 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49796
03:06:46 INFO - 1500977206187 Marionette DEBUG Closed connection 357
03:06:46 INFO - 1500977206188 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49797
03:06:46 INFO - 1500977206189 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:06:46 INFO - 1500977206190 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206193 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206196 Marionette TRACE 358 <- [1,1,null,{"sessionId":"be8422bc-25c8-4623-89b9-56bb76b893d2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206197 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49347/clicks.html"}]
03:06:46 INFO - 1500977206199 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/hidden.html"
03:06:46 INFO - 1500977206209 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/hidden.html"
03:06:46 INFO - 1500977206209 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/hidden.html"
03:06:46 INFO - 1500977206217 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49347/clicks.html"
03:06:46 INFO - 1500977206222 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49347/clicks.html"
03:06:46 INFO - 1500977206234 Marionette TRACE 358 <- [1,2,null,{}]
03:06:46 INFO - 1500977206248 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:06:46 INFO - 1500977206254 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0129598b-7452-42a5-b076-b0c1ef6ee12a","ELEMENT":"0129598b-7452-42a5-b076-b0c1ef6ee12a"}}]
03:06:46 INFO - 1500977206256 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0129598b-7452-42a5-b076-b0c1ef6ee12a"}]
03:06:46 INFO - 1500977206301 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/clicks.html"
03:06:46 INFO - 1500977206310 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/clicks.html"
03:06:46 INFO - 1500977206310 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/clicks.html"
03:06:46 INFO - 1500977206318 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49347/test.html"
03:06:46 INFO - 1500977206322 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49347/test.html"
03:06:46 INFO - 1500977206328 Marionette TRACE 358 <- [1,4,null,{}]
03:06:46 INFO - 1500977206338 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:06:46 INFO - 1500977206342 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"62d59898-d38c-4c32-8b30-a229e29a5c93","ELEMENT":"62d59898-d38c-4c32-8b30-a229e29a5c93"}}]
03:06:46 INFO - 1500977206344 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:06:46 INFO - 1500977206344 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:06:46 INFO - 1500977206346 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}]
03:06:46 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500977206350 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:06:46 INFO - 1500977206351 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:06:46 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 207ms
03:06:46 INFO - TEST-START | test_click.py TestClick.test_container_element
03:06:46 INFO - 1500977206353 Marionette TRACE 358 <- [1,8,null,{}]
03:06:46 INFO - 1500977206354 Marionette DEBUG Closed connection 358
03:06:46 INFO - 1500977206355 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49800
03:06:46 INFO - 1500977206356 Marionette DEBUG Closed connection 359
03:06:46 INFO - 1500977206357 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49801
03:06:46 INFO - 1500977206358 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:46 INFO - 1500977206359 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206362 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206365 Marionette TRACE 360 <- [1,1,null,{"sessionId":"ff9e2cf7-62ad-4140-a4a2-f716984907c9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206367 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:06:46 INFO - 1500977206367 Marionette TRACE 360 <- [1,2,null,{}]
03:06:46 INFO - 1500977206368 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:06:46 INFO - 1500977206369 Marionette TRACE 360 <- [1,3,null,{}]
03:06:46 INFO - 1500977206370 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:06:46 INFO - 1500977206370 Marionette TRACE 360 <- [1,4,null,{}]
03:06:46 INFO - 1500977206372 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}]
03:06:46 INFO - TEST-START: test_click.py TestClick.test_container_element1500977206375 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:06:46 INFO - 1500977206376 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:06:46 INFO - 1500977206378 Marionette TRACE 360 <- [1,6,null,{}]
03:06:46 INFO - 1500977206379 Marionette DEBUG Closed connection 360
03:06:46 INFO - 1500977206380 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49802
03:06:46 INFO - 1500977206381 Marionette DEBUG Closed connection 361
03:06:46 INFO - 1500977206382 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49803
03:06:46 INFO - 1500977206383 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:46 INFO - 1500977206383 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206386 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206390 Marionette TRACE 362 <- [1,1,null,{"sessionId":"85069d21-f2a7-4d04-97d8-002306460b16","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206391 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:06:46 INFO - 1500977206392 Marionette TRACE 362 <- [1,2,null,{}]
03:06:46 INFO - 1500977206394 Marionette DEBUG Closed connection 362
03:06:46 INFO - 1500977206395 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49804
03:06:46 INFO - 1500977206395 Marionette DEBUG Closed connection 363
03:06:46 INFO - 1500977206396 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49805
03:06:46 INFO - 1500977206397 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:06:46 INFO - 1500977206398 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206401 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206405 Marionette TRACE 364 <- [1,1,null,{"sessionId":"20b1043e-0881-4ee5-a35f-e964f67eac1c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206406 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"}]
03:06:46 INFO - 1500977206408 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49347/test.html"
03:06:46 INFO - 1500977206410 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49347/test.html"
03:06:46 INFO - 1500977206411 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49347/test.html"
03:06:46 INFO - 1500977206418 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"
03:06:46 INFO - 1500977206421 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"
03:06:46 INFO - 1500977206430 Marionette TRACE 364 <- [1,2,null,{}]
03:06:46 INFO - 1500977206443 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:06:46 INFO - 1500977206448 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"74493440-f41c-4ca5-acd4-caef87eef2a3","ELEMENT":"74493440-f41c-4ca5-acd4-caef87eef2a3"}}]
03:06:46 INFO - 1500977206450 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"74493440-f41c-4ca5-acd4-caef87eef2a3"}]
03:06:46 INFO - 1500977206697 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:06:46 INFO - 1500977206698 Marionette TRACE 364 <- [1,4,null,{}]
03:06:46 INFO - 1500977206699 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"74493440-f41c-4ca5-acd4-caef87eef2a3","name":"selected"}]
03:06:46 INFO - 1500977206704 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:06:46 INFO - 1500977206706 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}]
03:06:46 INFO - TEST-END: test_click.py TestClick.test_container_element1500977206709 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:06:46 INFO - 1500977206710 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:06:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 358ms
03:06:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:06:46 INFO - 1500977206712 Marionette TRACE 364 <- [1,7,null,{}]
03:06:46 INFO - 1500977206713 Marionette DEBUG Closed connection 364
03:06:46 INFO - 1500977206716 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49806
03:06:46 INFO - 1500977206717 Marionette DEBUG Closed connection 365
03:06:46 INFO - 1500977206717 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49807
03:06:46 INFO - 1500977206718 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:46 INFO - 1500977206719 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206722 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206726 Marionette TRACE 366 <- [1,1,null,{"sessionId":"a70a1c62-ff5e-470d-9f9a-043a3e2938eb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206727 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:06:46 INFO - 1500977206727 Marionette TRACE 366 <- [1,2,null,{}]
03:06:46 INFO - 1500977206728 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:06:46 INFO - 1500977206729 Marionette TRACE 366 <- [1,3,null,{}]
03:06:46 INFO - 1500977206730 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:06:46 INFO - 1500977206730 Marionette TRACE 366 <- [1,4,null,{}]
03:06:46 INFO - 1500977206733 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}]
03:06:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500977206736 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:06:46 INFO - 1500977206737 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:06:46 INFO - 1500977206739 Marionette TRACE 366 <- [1,6,null,{}]
03:06:46 INFO - 1500977206741 Marionette DEBUG Closed connection 366
03:06:46 INFO - 1500977206741 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49808
03:06:46 INFO - 1500977206742 Marionette DEBUG Closed connection 367
03:06:46 INFO - 1500977206743 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49809
03:06:46 INFO - 1500977206744 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:46 INFO - 1500977206744 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206747 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206751 Marionette TRACE 368 <- [1,1,null,{"sessionId":"17bdf806-ea95-46aa-a2ef-097d2f7bb625","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206752 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:06:46 INFO - 1500977206753 Marionette TRACE 368 <- [1,2,null,{}]
03:06:46 INFO - 1500977206755 Marionette DEBUG Closed connection 368
03:06:46 INFO - 1500977206756 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49810
03:06:46 INFO - 1500977206757 Marionette DEBUG Closed connection 369
03:06:46 INFO - 1500977206757 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49811
03:06:46 INFO - 1500977206758 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:06:46 INFO - 1500977206759 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:46 INFO - 1500977206762 Marionette DEBUG Register listener.js for window 4294967297
03:06:46 INFO - 1500977206765 Marionette TRACE 370 <- [1,1,null,{"sessionId":"4eb9c3b0-99d5-4b8e-8011-ccfdeb4d2375","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:46 INFO - 1500977206766 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"}]
03:06:46 INFO - 1500977206769 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"
03:06:46 INFO - 1500977206772 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"
03:06:46 INFO - 1500977206773 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"
03:06:46 INFO - 1500977206784 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"
03:06:46 INFO - 1500977206788 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"
03:06:46 INFO - 1500977206794 Marionette TRACE 370 <- [1,2,null,{}]
03:06:46 INFO - 1500977206803 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:06:46 INFO - 1500977206807 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d3cdf6a8-4acb-4000-a6ae-25e440a87525","ELEMENT":"d3cdf6a8-4acb-4000-a6ae-25e440a87525"}}]
03:06:46 INFO - 1500977206808 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d3cdf6a8-4acb-4000-a6ae-25e440a87525"}]
03:06:47 INFO - 1500977207054 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:06:47 INFO - 1500977207055 Marionette TRACE 370 <- [1,4,null,{}]
03:06:47 INFO - 1500977207056 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d3cdf6a8-4acb-4000-a6ae-25e440a87525","name":"selected"}]
03:06:47 INFO - 1500977207061 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:06:47 INFO - 1500977207062 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}]
03:06:47 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500977207066 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:06:47 INFO - 1500977207067 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:06:47 INFO - 1500977207068 Marionette TRACE 370 <- [1,7,null,{}]
03:06:47 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 356ms
03:06:47 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:06:47 INFO - 1500977207070 Marionette DEBUG Closed connection 370
03:06:47 INFO - 1500977207071 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49812
03:06:47 INFO - 1500977207072 Marionette DEBUG Closed connection 371
03:06:47 INFO - 1500977207072 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49813
03:06:47 INFO - 1500977207073 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:47 INFO - 1500977207074 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207077 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207081 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d5c08e1b-7fcf-4781-b710-c47729e51607","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207082 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:06:47 INFO - 1500977207082 Marionette TRACE 372 <- [1,2,null,{}]
03:06:47 INFO - 1500977207083 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:06:47 INFO - 1500977207084 Marionette TRACE 372 <- [1,3,null,{}]
03:06:47 INFO - 1500977207085 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:06:47 INFO - 1500977207085 Marionette TRACE 372 <- [1,4,null,{}]
03:06:47 INFO - 1500977207087 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}]
03:06:47 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500977207090 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:06:47 INFO - 1500977207091 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:06:47 INFO - 1500977207092 Marionette TRACE 372 <- [1,6,null,{}]
03:06:47 INFO - 1500977207094 Marionette DEBUG Closed connection 372
03:06:47 INFO - 1500977207095 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49814
03:06:47 INFO - 1500977207096 Marionette DEBUG Closed connection 373
03:06:47 INFO - 1500977207097 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49815
03:06:47 INFO - 1500977207098 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:47 INFO - 1500977207098 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207101 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207104 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2c1fd7b4-de40-4d92-9c98-200fa1651fdf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207106 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:06:47 INFO - 1500977207107 Marionette TRACE 374 <- [1,2,null,{}]
03:06:47 INFO - 1500977207109 Marionette DEBUG Closed connection 374
03:06:47 INFO - 1500977207110 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49816
03:06:47 INFO - 1500977207110 Marionette DEBUG Closed connection 375
03:06:47 INFO - 1500977207111 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49817
03:06:47 INFO - 1500977207112 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:06:47 INFO - 1500977207113 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207116 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207119 Marionette TRACE 376 <- [1,1,null,{"sessionId":"4941713b-7f74-49ec-8439-2c8b6fa33c4f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207121 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"}]
03:06:47 INFO - 1500977207123 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"
03:06:47 INFO - 1500977207125 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"
03:06:47 INFO - 1500977207125 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"
03:06:47 INFO - 1500977207143 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"
03:06:47 INFO - 1500977207145 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"
03:06:47 INFO - 1500977207155 Marionette TRACE 376 <- [1,2,null,{}]
03:06:47 INFO - 1500977207159 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:06:47 INFO - 1500977207162 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5360174e-7d4d-499e-b61d-5de910fedd8c","ELEMENT":"5360174e-7d4d-499e-b61d-5de910fedd8c"}}]
03:06:47 INFO - 1500977207163 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"5360174e-7d4d-499e-b61d-5de910fedd8c"}]
03:06:47 INFO - 1500977207411 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:06:47 INFO - 1500977207412 Marionette TRACE 376 <- [1,4,null,{}]
03:06:47 INFO - 1500977207414 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}]
03:06:47 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500977207417 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:06:47 INFO - 1500977207418 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:06:47 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 351ms
03:06:47 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:06:47 INFO - 1500977207420 Marionette TRACE 376 <- [1,6,null,{}]
03:06:47 INFO - 1500977207421 Marionette DEBUG Closed connection 376
03:06:47 INFO - 1500977207422 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49818
03:06:47 INFO - 1500977207423 Marionette DEBUG Closed connection 377
03:06:47 INFO - 1500977207424 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49819
03:06:47 INFO - 1500977207425 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:47 INFO - 1500977207426 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207429 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207432 Marionette TRACE 378 <- [1,1,null,{"sessionId":"226772f1-ad3c-4e72-a98f-c85db465a730","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207433 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:06:47 INFO - 1500977207434 Marionette TRACE 378 <- [1,2,null,{}]
03:06:47 INFO - 1500977207435 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:06:47 INFO - 1500977207435 Marionette TRACE 378 <- [1,3,null,{}]
03:06:47 INFO - 1500977207436 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:06:47 INFO - 1500977207437 Marionette TRACE 378 <- [1,4,null,{}]
03:06:47 INFO - 1500977207439 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}]
03:06:47 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500977207441 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:06:47 INFO - 1500977207442 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:06:47 INFO - 1500977207444 Marionette TRACE 378 <- [1,6,null,{}]
03:06:47 INFO - 1500977207446 Marionette DEBUG Closed connection 378
03:06:47 INFO - 1500977207446 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49820
03:06:47 INFO - 1500977207447 Marionette DEBUG Closed connection 379
03:06:47 INFO - 1500977207448 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49821
03:06:47 INFO - 1500977207449 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:47 INFO - 1500977207450 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207452 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207456 Marionette TRACE 380 <- [1,1,null,{"sessionId":"98eb4f63-f98e-4e80-bfab-72228d7dea27","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207457 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:06:47 INFO - 1500977207459 Marionette TRACE 380 <- [1,2,null,{}]
03:06:47 INFO - 1500977207461 Marionette DEBUG Closed connection 380
03:06:47 INFO - 1500977207462 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49822
03:06:47 INFO - 1500977207462 Marionette DEBUG Closed connection 381
03:06:47 INFO - 1500977207463 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49823
03:06:47 INFO - 1500977207464 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:06:47 INFO - 1500977207465 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207468 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207471 Marionette TRACE 382 <- [1,1,null,{"sessionId":"e7492e21-27bc-4ed7-9eb8-38df2adf3ae9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207472 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"}]
03:06:47 INFO - 1500977207475 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"
03:06:47 INFO - 1500977207477 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"
03:06:47 INFO - 1500977207477 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"
03:06:47 INFO - 1500977207484 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"
03:06:47 INFO - 1500977207488 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"
03:06:47 INFO - 1500977207494 Marionette TRACE 382 <- [1,2,null,{}]
03:06:47 INFO - 1500977207505 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:06:47 INFO - 1500977207509 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4a16c72b-49dc-4c68-8ff7-006134e8bab0","ELEMENT":"4a16c72b-49dc-4c68-8ff7-006134e8bab0"}}]
03:06:47 INFO - 1500977207510 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"4a16c72b-49dc-4c68-8ff7-006134e8bab0"}]
03:06:47 INFO - 1500977207755 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:06:47 INFO - 1500977207756 Marionette TRACE 382 <- [1,4,null,{}]
03:06:47 INFO - 1500977207757 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}]
03:06:47 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500977207761 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:06:47 INFO - 1500977207762 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:06:47 INFO - 1500977207763 Marionette TRACE 382 <- [1,6,null,{}]
03:06:47 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 343ms
03:06:47 INFO - TEST-START | test_click.py TestClick.test_input_file
03:06:47 INFO - 1500977207765 Marionette DEBUG Closed connection 382
03:06:47 INFO - 1500977207766 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49824
03:06:47 INFO - 1500977207767 Marionette DEBUG Closed connection 383
03:06:47 INFO - 1500977207768 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49825
03:06:47 INFO - 1500977207769 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:47 INFO - 1500977207769 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207772 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207776 Marionette TRACE 384 <- [1,1,null,{"sessionId":"3eca020a-efa0-46fa-ae45-354a2b31ce6b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207777 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:06:47 INFO - 1500977207777 Marionette TRACE 384 <- [1,2,null,{}]
03:06:47 INFO - 1500977207778 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:06:47 INFO - 1500977207779 Marionette TRACE 384 <- [1,3,null,{}]
03:06:47 INFO - 1500977207780 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:06:47 INFO - 1500977207780 Marionette TRACE 384 <- [1,4,null,{}]
03:06:47 INFO - 1500977207782 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}]
03:06:47 INFO - TEST-START: test_click.py TestClick.test_input_file1500977207785 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:06:47 INFO - 1500977207786 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:06:47 INFO - 1500977207788 Marionette TRACE 384 <- [1,6,null,{}]
03:06:47 INFO - 1500977207789 Marionette DEBUG Closed connection 384
03:06:47 INFO - 1500977207790 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49826
03:06:47 INFO - 1500977207791 Marionette DEBUG Closed connection 385
03:06:47 INFO - 1500977207791 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49827
03:06:47 INFO - 1500977207793 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:47 INFO - 1500977207793 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207796 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207799 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b58fa4ae-e9fd-43da-af46-d3cc6ee63583","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207800 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:06:47 INFO - 1500977207802 Marionette TRACE 386 <- [1,2,null,{}]
03:06:47 INFO - 1500977207804 Marionette DEBUG Closed connection 386
03:06:47 INFO - 1500977207804 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49828
03:06:47 INFO - 1500977207805 Marionette DEBUG Closed connection 387
03:06:47 INFO - 1500977207806 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49829
03:06:47 INFO - 1500977207807 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:06:47 INFO - 1500977207808 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207811 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207814 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ef62dede-eb3c-4c8b-9751-0dcd2c6ad52b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207815 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:06:47 INFO - 1500977207817 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"
03:06:47 INFO - 1500977207819 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"
03:06:47 INFO - 1500977207820 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"
03:06:47 INFO - 1500977207827 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:06:47 INFO - 1500977207831 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:06:47 INFO - 1500977207839 Marionette TRACE 388 <- [1,2,null,{}]
03:06:47 INFO - 1500977207844 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:06:47 INFO - 1500977207852 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93a2a581-ac35-4310-8a4f-8fc53f3fec2f","ELEMENT":"93a2a581-ac35-4310-8a4f-8fc53f3fec2f"}}]
03:06:47 INFO - 1500977207853 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"93a2a581-ac35-4310-8a4f-8fc53f3fec2f"}]
03:06:47 INFO - 1500977207882 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:06:47 INFO - 1500977207884 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}]
03:06:47 INFO - TEST-END: test_click.py TestClick.test_input_file1500977207887 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:06:47 INFO - 1500977207888 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:06:47 INFO - 1500977207889 Marionette TRACE 388 <- [1,6,null,{}]
03:06:47 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 126ms
03:06:47 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:06:47 INFO - 1500977207891 Marionette DEBUG Closed connection 388
03:06:47 INFO - 1500977207892 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49830
03:06:47 INFO - 1500977207893 Marionette DEBUG Closed connection 389
03:06:47 INFO - 1500977207893 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49831
03:06:47 INFO - 1500977207894 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:47 INFO - 1500977207895 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207898 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207902 Marionette TRACE 390 <- [1,1,null,{"sessionId":"4ecbc017-bd9d-48c3-82a1-6d74ee69c37a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207903 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:06:47 INFO - 1500977207903 Marionette TRACE 390 <- [1,2,null,{}]
03:06:47 INFO - 1500977207904 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:06:47 INFO - 1500977207905 Marionette TRACE 390 <- [1,3,null,{}]
03:06:47 INFO - 1500977207906 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:06:47 INFO - 1500977207906 Marionette TRACE 390 <- [1,4,null,{}]
03:06:47 INFO - 1500977207908 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}]
03:06:47 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500977207911 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:06:47 INFO - 1500977207912 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:06:47 INFO - 1500977207914 Marionette TRACE 390 <- [1,6,null,{}]
03:06:47 INFO - 1500977207915 Marionette DEBUG Closed connection 390
03:06:47 INFO - 1500977207916 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49832
03:06:47 INFO - 1500977207917 Marionette DEBUG Closed connection 391
03:06:47 INFO - 1500977207918 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49833
03:06:47 INFO - 1500977207919 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:47 INFO - 1500977207919 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207922 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207926 Marionette TRACE 392 <- [1,1,null,{"sessionId":"4df08035-caf3-418e-8190-a8b819a224a4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207927 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:06:47 INFO - 1500977207928 Marionette TRACE 392 <- [1,2,null,{}]
03:06:47 INFO - 1500977207930 Marionette DEBUG Closed connection 392
03:06:47 INFO - 1500977207931 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49834
03:06:47 INFO - 1500977207932 Marionette DEBUG Closed connection 393
03:06:47 INFO - 1500977207932 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49835
03:06:47 INFO - 1500977207933 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:06:47 INFO - 1500977207934 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:47 INFO - 1500977207937 Marionette DEBUG Register listener.js for window 4294967297
03:06:47 INFO - 1500977207940 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2a7f1579-da43-4b86-aecd-9855eb2f22c0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:47 INFO - 1500977207941 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"}]
03:06:47 INFO - 1500977207944 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:06:47 INFO - 1500977207946 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:06:47 INFO - 1500977207946 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:06:47 INFO - 1500977207954 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"
03:06:47 INFO - 1500977207957 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"
03:06:47 INFO - 1500977207961 Marionette TRACE 394 <- [1,2,null,{}]
03:06:47 INFO - 1500977207973 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:06:47 INFO - 1500977207977 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b121709d-9585-4f90-b0dd-611a7128fa1f","ELEMENT":"b121709d-9585-4f90-b0dd-611a7128fa1f"}}]
03:06:47 INFO - 1500977207978 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:06:47 INFO - 1500977207981 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"02c7855d-4f97-415d-b469-ec8386025efd","ELEMENT":"02c7855d-4f97-415d-b469-ec8386025efd"}}]
03:06:47 INFO - 1500977207982 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b121709d-9585-4f90-b0dd-611a7128fa1f"}]
03:06:48 INFO - 1500977208234 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:06:48 INFO - 1500977208235 Marionette TRACE 394 <- [1,5,null,{}]
03:06:48 INFO - 1500977208236 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"02c7855d-4f97-415d-b469-ec8386025efd"}]
03:06:48 INFO - 1500977208254 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:06:48 INFO - 1500977208256 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:06:48 INFO - 1500977208259 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:06:48 INFO - 1500977208260 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}]
03:06:48 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 377ms
03:06:48 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:06:48 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500977208264 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:06:48 INFO - 1500977208265 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:06:48 INFO - 1500977208266 Marionette TRACE 394 <- [1,9,null,{}]
03:06:48 INFO - 1500977208268 Marionette DEBUG Closed connection 394
03:06:48 INFO - 1500977208269 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49836
03:06:48 INFO - 1500977208270 Marionette DEBUG Closed connection 395
03:06:48 INFO - 1500977208270 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49837
03:06:48 INFO - 1500977208272 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:48 INFO - 1500977208272 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:48 INFO - 1500977208275 Marionette DEBUG Register listener.js for window 4294967297
03:06:48 INFO - 1500977208279 Marionette TRACE 396 <- [1,1,null,{"sessionId":"d3d25c94-70fc-4815-b753-bd2a2431b800","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:48 INFO - 1500977208280 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:06:48 INFO - 1500977208280 Marionette TRACE 396 <- [1,2,null,{}]
03:06:48 INFO - 1500977208281 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:06:48 INFO - 1500977208282 Marionette TRACE 396 <- [1,3,null,{}]
03:06:48 INFO - 1500977208283 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:06:48 INFO - 1500977208283 Marionette TRACE 396 <- [1,4,null,{}]
03:06:48 INFO - 1500977208285 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}]
03:06:48 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500977208288 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:06:48 INFO - 1500977208289 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:06:48 INFO - 1500977208290 Marionette TRACE 396 <- [1,6,null,{}]
03:06:48 INFO - 1500977208292 Marionette DEBUG Closed connection 396
03:06:48 INFO - 1500977208293 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49838
03:06:48 INFO - 1500977208293 Marionette DEBUG Closed connection 397
03:06:48 INFO - 1500977208294 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49839
03:06:48 INFO - 1500977208295 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:06:48 INFO - 1500977208296 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:48 INFO - 1500977208299 Marionette DEBUG Register listener.js for window 4294967297
03:06:48 INFO - 1500977208302 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4739a304-517d-4c41-99aa-01d353d94c7b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:48 INFO - 1500977208304 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:06:48 INFO - 1500977208305 Marionette TRACE 398 <- [1,2,null,{}]
03:06:48 INFO - 1500977208307 Marionette DEBUG Closed connection 398
03:06:48 INFO - 1500977208308 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49840
03:06:48 INFO - 1500977208308 Marionette DEBUG Closed connection 399
03:06:48 INFO - 1500977208309 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49841
03:06:48 INFO - 1500977208310 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:06:48 INFO - 1500977208311 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}
03:06:48 INFO - 1500977208314 Marionette DEBUG Register listener.js for window 4294967297
03:06:48 INFO - 1500977208317 Marionette TRACE 400 <- [1,1,null,{"sessionId":"574c3012-ace8-4f2b-bf83-860ac4120b56","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpnnbvnv.mozrunner","moz:accessibilityChecks":false}}]
03:06:48 INFO - 1500977208318 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"}]
03:06:48 INFO - 1500977208321 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"
03:06:48 INFO - 1500977208323 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"
03:06:48 INFO - 1500977208323 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"
03:06:48 INFO - 1500977208333 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"
03:06:48 INFO - 1500977208335 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"
03:06:48 INFO - 1500977208344 Marionette TRACE 400 <- [1,2,null,{}]
03:06:48 INFO - 1500977208354 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:06:48 INFO - 1500977208356 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"126a2a59-070e-4426-9e27-48978948f3b5","ELEMENT":"126a2a59-070e-4426-9e27-48978948f3b5"}}]
03:06:48 INFO - 1500977208357 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"126a2a59-070e-4426-9e27-48978948f3b5"}]
03:06:48 INFO - 1500977208381 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:06:48 INFO - 1500977208382 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"126a2a59-070e-4426-9e27-48978948f3b5"}]
03:06:48 INFO - 1500977208400 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element