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]
19:00:00 INFO - 1500516000115 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}]
19:00:00 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500516000119 Marionette TRACE 352 <- [1,5,null,{"value":null}]
19:00:00 INFO - 1500516000120 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
19:00:00 INFO - 1500516000122 Marionette TRACE 352 <- [1,6,null,{}]
19:00:00 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 166ms
19:00:00 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:00:00 INFO - 1500516000125 Marionette DEBUG Closed connection 352
19:00:00 INFO - 1500516000126 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50277
19:00:00 INFO - 1500516000127 Marionette DEBUG Closed connection 353
19:00:00 INFO - 1500516000139 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50278
19:00:00 INFO - 1500516000141 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:00 INFO - 1500516000141 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000151 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000156 Marionette TRACE 354 <- [1,1,null,{"sessionId":"941db4b1-b13e-42e5-9d8c-12a5ac14aab7","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000158 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
19:00:00 INFO - 1500516000158 Marionette TRACE 354 <- [1,2,null,{}]
19:00:00 INFO - 1500516000159 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:00:00 INFO - 1500516000160 Marionette TRACE 354 <- [1,3,null,{}]
19:00:00 INFO - 1500516000161 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
19:00:00 INFO - 1500516000161 Marionette TRACE 354 <- [1,4,null,{}]
19:00:00 INFO - 1500516000163 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}]
19:00:00 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500516000166 Marionette TRACE 354 <- [1,5,null,{"value":null}]
19:00:00 INFO - 1500516000167 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
19:00:00 INFO - 1500516000169 Marionette TRACE 354 <- [1,6,null,{}]
19:00:00 INFO - 1500516000171 Marionette DEBUG Closed connection 354
19:00:00 INFO - 1500516000172 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50279
19:00:00 INFO - 1500516000173 Marionette DEBUG Closed connection 355
19:00:00 INFO - 1500516000174 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50280
19:00:00 INFO - 1500516000175 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:00 INFO - 1500516000176 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000178 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000182 Marionette TRACE 356 <- [1,1,null,{"sessionId":"90fef66a-2eea-4dd1-aff9-db710506a53c","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000184 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
19:00:00 INFO - 1500516000185 Marionette TRACE 356 <- [1,2,null,{}]
19:00:00 INFO - 1500516000188 Marionette DEBUG Closed connection 356
19:00:00 INFO - 1500516000188 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50281
19:00:00 INFO - 1500516000189 Marionette DEBUG Closed connection 357
19:00:00 INFO - 1500516000190 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50282
19:00:00 INFO - 1500516000191 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:00:00 INFO - 1500516000192 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000194 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000198 Marionette TRACE 358 <- [1,1,null,{"sessionId":"47ba2d7a-be50-4f21-a629-3dfa9f7cbb9a","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000200 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49840/clicks.html"}]
19:00:00 INFO - 1500516000202 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49840/hidden.html"
19:00:00 INFO - 1500516000208 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49840/hidden.html"
19:00:00 INFO - 1500516000208 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49840/hidden.html"
19:00:00 INFO - 1500516000250 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49840/clicks.html"
19:00:00 INFO - 1500516000252 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49840/clicks.html"
19:00:00 INFO - 1500516000256 Marionette TRACE 358 <- [1,2,null,{}]
19:00:00 INFO - 1500516000264 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:00:00 INFO - 1500516000267 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"998153c6-bccf-4d5e-a81c-1eda7fa385a0","ELEMENT":"998153c6-bccf-4d5e-a81c-1eda7fa385a0"}}]
19:00:00 INFO - 1500516000275 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"998153c6-bccf-4d5e-a81c-1eda7fa385a0"}]
19:00:00 INFO - 1500516000317 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49840/clicks.html"
19:00:00 INFO - 1500516000331 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49840/clicks.html"
19:00:00 INFO - 1500516000331 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49840/clicks.html"
19:00:00 INFO - 1500516000380 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49840/test.html"
19:00:00 INFO - 1500516000384 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49840/test.html"
19:00:00 INFO - 1500516000390 Marionette TRACE 358 <- [1,4,null,{}]
19:00:00 INFO - 1500516000401 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:00:00 INFO - 1500516000404 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dc0fabfa-0137-494e-b2b3-1fdb9bdc5c5e","ELEMENT":"dc0fabfa-0137-494e-b2b3-1fdb9bdc5c5e"}}]
19:00:00 INFO - 1500516000405 Marionette TRACE 358 -> [0,6,"getTitle",{}]
19:00:00 INFO - 1500516000406 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
19:00:00 INFO - 1500516000423 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}]
19:00:00 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500516000426 Marionette TRACE 358 <- [1,7,null,{"value":null}]
19:00:00 INFO - 1500516000428 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
19:00:00 INFO - 1500516000429 Marionette TRACE 358 <- [1,8,null,{}]
19:00:00 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 307ms
19:00:00 INFO - TEST-START | test_click.py TestClick.test_container_element
19:00:00 INFO - 1500516000432 Marionette DEBUG Closed connection 358
19:00:00 INFO - 1500516000433 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50285
19:00:00 INFO - 1500516000434 Marionette DEBUG Closed connection 359
19:00:00 INFO - 1500516000434 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50286
19:00:00 INFO - 1500516000435 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:00 INFO - 1500516000436 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000439 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000443 Marionette TRACE 360 <- [1,1,null,{"sessionId":"de862316-cd93-4dd3-a18f-0d5f4cccdeb4","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000445 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
19:00:00 INFO - 1500516000445 Marionette TRACE 360 <- [1,2,null,{}]
19:00:00 INFO - 1500516000446 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:00:00 INFO - 1500516000447 Marionette TRACE 360 <- [1,3,null,{}]
19:00:00 INFO - 1500516000448 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
19:00:00 INFO - 1500516000448 Marionette TRACE 360 <- [1,4,null,{}]
19:00:00 INFO - 1500516000450 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}]
19:00:00 INFO - TEST-START: test_click.py TestClick.test_container_element1500516000453 Marionette TRACE 360 <- [1,5,null,{"value":null}]
19:00:00 INFO - 1500516000454 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
19:00:00 INFO - 1500516000456 Marionette TRACE 360 <- [1,6,null,{}]
19:00:00 INFO - 1500516000486 Marionette DEBUG Closed connection 360
19:00:00 INFO - 1500516000487 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50287
19:00:00 INFO - 1500516000488 Marionette DEBUG Closed connection 361
19:00:00 INFO - 1500516000489 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50288
19:00:00 INFO - 1500516000505 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:00 INFO - 1500516000506 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000509 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000513 Marionette TRACE 362 <- [1,1,null,{"sessionId":"84e14ca8-3612-4c48-8b8f-c137bf1663f3","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000528 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
19:00:00 INFO - 1500516000530 Marionette TRACE 362 <- [1,2,null,{}]
19:00:00 INFO - 1500516000533 Marionette DEBUG Closed connection 362
19:00:00 INFO - 1500516000534 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50289
19:00:00 INFO - 1500516000536 Marionette DEBUG Closed connection 363
19:00:00 INFO - 1500516000537 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50290
19:00:00 INFO - 1500516000538 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:00:00 INFO - 1500516000539 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000542 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000546 Marionette TRACE 364 <- [1,1,null,{"sessionId":"e12c5a8e-4b34-4b00-9350-30726f12d3e4","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000549 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"}]
19:00:00 INFO - 1500516000551 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49840/test.html"
19:00:00 INFO - 1500516000553 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49840/test.html"
19:00:00 INFO - 1500516000553 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49840/test.html"
19:00:00 INFO - 1500516000584 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"
19:00:00 INFO - 1500516000588 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"
19:00:00 INFO - 1500516000592 Marionette TRACE 364 <- [1,2,null,{}]
19:00:00 INFO - 1500516000603 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:00:00 INFO - 1500516000606 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cc6a9e36-f91a-4523-8f09-58a546a8a280","ELEMENT":"cc6a9e36-f91a-4523-8f09-58a546a8a280"}}]
19:00:00 INFO - 1500516000607 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"cc6a9e36-f91a-4523-8f09-58a546a8a280"}]
19:00:00 INFO - 1500516000853 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:00:00 INFO - 1500516000854 Marionette TRACE 364 <- [1,4,null,{}]
19:00:00 INFO - 1500516000855 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"cc6a9e36-f91a-4523-8f09-58a546a8a280","name":"selected"}]
19:00:00 INFO - 1500516000861 Marionette TRACE 364 <- [1,5,null,{"value":true}]
19:00:00 INFO - 1500516000863 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}]
19:00:00 INFO - TEST-END: test_click.py TestClick.test_container_element1500516000867 Marionette TRACE 364 <- [1,6,null,{"value":null}]
19:00:00 INFO - 1500516000868 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
19:00:00 INFO - 1500516000870 Marionette TRACE 364 <- [1,7,null,{}]
19:00:00 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 440ms
19:00:00 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:00:00 INFO - 1500516000873 Marionette DEBUG Closed connection 364
19:00:00 INFO - 1500516000874 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50291
19:00:00 INFO - 1500516000875 Marionette DEBUG Closed connection 365
19:00:00 INFO - 1500516000875 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50292
19:00:00 INFO - 1500516000877 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:00 INFO - 1500516000877 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000880 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000893 Marionette TRACE 366 <- [1,1,null,{"sessionId":"bcc997e5-76af-41c4-978a-3c41564c7391","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000895 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
19:00:00 INFO - 1500516000896 Marionette TRACE 366 <- [1,2,null,{}]
19:00:00 INFO - 1500516000897 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:00:00 INFO - 1500516000898 Marionette TRACE 366 <- [1,3,null,{}]
19:00:00 INFO - 1500516000899 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
19:00:00 INFO - 1500516000899 Marionette TRACE 366 <- [1,4,null,{}]
19:00:00 INFO - 1500516000902 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}]
19:00:00 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500516000905 Marionette TRACE 366 <- [1,5,null,{"value":null}]
19:00:00 INFO - 1500516000907 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
19:00:00 INFO - 1500516000909 Marionette TRACE 366 <- [1,6,null,{}]
19:00:00 INFO - 1500516000911 Marionette DEBUG Closed connection 366
19:00:00 INFO - 1500516000912 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50293
19:00:00 INFO - 1500516000913 Marionette DEBUG Closed connection 367
19:00:00 INFO - 1500516000914 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50294
19:00:00 INFO - 1500516000915 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:00 INFO - 1500516000916 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000919 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000923 Marionette TRACE 368 <- [1,1,null,{"sessionId":"53ef7ae2-8c50-4f12-af09-fbc8b46e65e0","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000925 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
19:00:00 INFO - 1500516000927 Marionette TRACE 368 <- [1,2,null,{}]
19:00:00 INFO - 1500516000929 Marionette DEBUG Closed connection 368
19:00:00 INFO - 1500516000930 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50295
19:00:00 INFO - 1500516000931 Marionette DEBUG Closed connection 369
19:00:00 INFO - 1500516000932 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50296
19:00:00 INFO - 1500516000933 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:00:00 INFO - 1500516000934 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:00 INFO - 1500516000937 Marionette DEBUG Register listener.js for window 15
19:00:00 INFO - 1500516000941 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d15f318a-92c2-47d8-b816-f1289d5d9c27","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:00 INFO - 1500516000943 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"}]
19:00:00 INFO - 1500516000946 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"
19:00:00 INFO - 1500516000948 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"
19:00:00 INFO - 1500516000949 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"
19:00:00 INFO - 1500516000972 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"
19:00:00 INFO - 1500516000975 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"
19:00:00 INFO - 1500516000987 Marionette TRACE 370 <- [1,2,null,{}]
19:00:00 INFO - 1500516000989 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:00:00 INFO - 1500516000992 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b78d48f2-8c1f-4861-bbc3-df6622a333ad","ELEMENT":"b78d48f2-8c1f-4861-bbc3-df6622a333ad"}}]
19:00:00 INFO - 1500516000996 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"b78d48f2-8c1f-4861-bbc3-df6622a333ad"}]
19:00:01 INFO - 1500516001245 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:00:01 INFO - 1500516001246 Marionette TRACE 370 <- [1,4,null,{}]
19:00:01 INFO - 1500516001247 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"b78d48f2-8c1f-4861-bbc3-df6622a333ad","name":"selected"}]
19:00:01 INFO - 1500516001252 Marionette TRACE 370 <- [1,5,null,{"value":true}]
19:00:01 INFO - 1500516001254 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}]
19:00:01 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500516001257 Marionette TRACE 370 <- [1,6,null,{"value":null}]
19:00:01 INFO - 1500516001258 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
19:00:01 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 389ms
19:00:01 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:00:01 INFO - 1500516001260 Marionette TRACE 370 <- [1,7,null,{}]
19:00:01 INFO - 1500516001262 Marionette DEBUG Closed connection 370
19:00:01 INFO - 1500516001263 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50297
19:00:01 INFO - 1500516001264 Marionette DEBUG Closed connection 371
19:00:01 INFO - 1500516001265 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50298
19:00:01 INFO - 1500516001266 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:01 INFO - 1500516001267 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:01 INFO - 1500516001269 Marionette DEBUG Register listener.js for window 15
19:00:01 INFO - 1500516001273 Marionette TRACE 372 <- [1,1,null,{"sessionId":"be84d165-d34d-4cce-93ff-0940e8aa1cc8","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:01 INFO - 1500516001275 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
19:00:01 INFO - 1500516001276 Marionette TRACE 372 <- [1,2,null,{}]
19:00:01 INFO - 1500516001277 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:00:01 INFO - 1500516001277 Marionette TRACE 372 <- [1,3,null,{}]
19:00:01 INFO - 1500516001278 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
19:00:01 INFO - 1500516001279 Marionette TRACE 372 <- [1,4,null,{}]
19:00:01 INFO - 1500516001281 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}]
19:00:01 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500516001284 Marionette TRACE 372 <- [1,5,null,{"value":null}]
19:00:01 INFO - 1500516001285 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
19:00:01 INFO - 1500516001286 Marionette TRACE 372 <- [1,6,null,{}]
19:00:01 INFO - 1500516001289 Marionette DEBUG Closed connection 372
19:00:01 INFO - 1500516001290 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50299
19:00:01 INFO - 1500516001290 Marionette DEBUG Closed connection 373
19:00:01 INFO - 1500516001291 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50300
19:00:01 INFO - 1500516001292 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:01 INFO - 1500516001293 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:01 INFO - 1500516001296 Marionette DEBUG Register listener.js for window 15
19:00:01 INFO - 1500516001299 Marionette TRACE 374 <- [1,1,null,{"sessionId":"60997f39-9af1-489e-bd91-4be513d27aa7","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:01 INFO - 1500516001302 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
19:00:01 INFO - 1500516001303 Marionette TRACE 374 <- [1,2,null,{}]
19:00:01 INFO - 1500516001305 Marionette DEBUG Closed connection 374
19:00:01 INFO - 1500516001306 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50301
19:00:01 INFO - 1500516001307 Marionette DEBUG Closed connection 375
19:00:01 INFO - 1500516001308 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50302
19:00:01 INFO - 1500516001309 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:00:01 INFO - 1500516001310 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:01 INFO - 1500516001313 Marionette DEBUG Register listener.js for window 15
19:00:01 INFO - 1500516001318 Marionette TRACE 376 <- [1,1,null,{"sessionId":"cee9f268-adb6-436e-854a-79a0f7c3f0f5","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:01 INFO - 1500516001320 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"}]
19:00:01 INFO - 1500516001322 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"
19:00:01 INFO - 1500516001324 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"
19:00:01 INFO - 1500516001324 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"
19:00:01 INFO - 1500516001348 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"
19:00:01 INFO - 1500516001351 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"
19:00:01 INFO - 1500516001355 Marionette TRACE 376 <- [1,2,null,{}]
19:00:01 INFO - 1500516001364 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:00:01 INFO - 1500516001368 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88762aab-3bdf-44eb-8f3d-f704fcdc1e0c","ELEMENT":"88762aab-3bdf-44eb-8f3d-f704fcdc1e0c"}}]
19:00:01 INFO - 1500516001369 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"88762aab-3bdf-44eb-8f3d-f704fcdc1e0c"}]
19:00:01 INFO - 1500516001611 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:00:01 INFO - 1500516001612 Marionette TRACE 376 <- [1,4,null,{}]
19:00:01 INFO - 1500516001614 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}]
19:00:01 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500516001617 Marionette TRACE 376 <- [1,5,null,{"value":null}]
19:00:01 INFO - 1500516001618 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
19:00:01 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 360ms
19:00:01 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:00:01 INFO - 1500516001620 Marionette TRACE 376 <- [1,6,null,{}]
19:00:01 INFO - 1500516001622 Marionette DEBUG Closed connection 376
19:00:01 INFO - 1500516001623 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50303
19:00:01 INFO - 1500516001624 Marionette DEBUG Closed connection 377
19:00:01 INFO - 1500516001625 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50304
19:00:01 INFO - 1500516001626 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:01 INFO - 1500516001627 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:01 INFO - 1500516001630 Marionette DEBUG Register listener.js for window 15
19:00:01 INFO - 1500516001633 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9cd71509-02a9-4609-bf01-d7f65d63a508","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:01 INFO - 1500516001636 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
19:00:01 INFO - 1500516001636 Marionette TRACE 378 <- [1,2,null,{}]
19:00:01 INFO - 1500516001637 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:00:01 INFO - 1500516001638 Marionette TRACE 378 <- [1,3,null,{}]
19:00:01 INFO - 1500516001639 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
19:00:01 INFO - 1500516001639 Marionette TRACE 378 <- [1,4,null,{}]
19:00:01 INFO - 1500516001641 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}]
19:00:01 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500516001644 Marionette TRACE 378 <- [1,5,null,{"value":null}]
19:00:01 INFO - 1500516001645 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
19:00:01 INFO - 1500516001647 Marionette TRACE 378 <- [1,6,null,{}]
19:00:01 INFO - 1500516001649 Marionette DEBUG Closed connection 378
19:00:01 INFO - 1500516001650 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50305
19:00:01 INFO - 1500516001651 Marionette DEBUG Closed connection 379
19:00:01 INFO - 1500516001651 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50306
19:00:01 INFO - 1500516001653 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:01 INFO - 1500516001661 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:01 INFO - 1500516001664 Marionette DEBUG Register listener.js for window 15
19:00:01 INFO - 1500516001668 Marionette TRACE 380 <- [1,1,null,{"sessionId":"501af039-a683-49e8-b082-cbf7993aea38","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:01 INFO - 1500516001670 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
19:00:01 INFO - 1500516001671 Marionette TRACE 380 <- [1,2,null,{}]
19:00:01 INFO - 1500516001675 Marionette DEBUG Closed connection 380
19:00:01 INFO - 1500516001676 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50307
19:00:01 INFO - 1500516001677 Marionette DEBUG Closed connection 381
19:00:01 INFO - 1500516001678 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50308
19:00:01 INFO - 1500516001679 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:00:01 INFO - 1500516001680 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:01 INFO - 1500516001683 Marionette DEBUG Register listener.js for window 15
19:00:01 INFO - 1500516001687 Marionette TRACE 382 <- [1,1,null,{"sessionId":"cba60078-29a8-4f83-bc53-3eb1cb2ca88b","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:01 INFO - 1500516001689 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"}]
19:00:01 INFO - 1500516001691 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"
19:00:01 INFO - 1500516001693 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"
19:00:01 INFO - 1500516001693 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"
19:00:01 INFO - 1500516001717 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"
19:00:01 INFO - 1500516001719 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"
19:00:01 INFO - 1500516001724 Marionette TRACE 382 <- [1,2,null,{}]
19:00:01 INFO - 1500516001735 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:00:01 INFO - 1500516001739 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"004542f4-270b-46ee-a19a-14353ea13a9e","ELEMENT":"004542f4-270b-46ee-a19a-14353ea13a9e"}}]
19:00:01 INFO - 1500516001740 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"004542f4-270b-46ee-a19a-14353ea13a9e"}]
19:00:01 INFO - 1500516001987 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:00:01 INFO - 1500516001988 Marionette TRACE 382 <- [1,4,null,{}]
19:00:01 INFO - 1500516001990 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}]
19:00:01 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500516001993 Marionette TRACE 382 <- [1,5,null,{"value":null}]
19:00:01 INFO - 1500516001994 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
19:00:01 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 375ms
19:00:01 INFO - 1500516001996 Marionette TRACE 382 <- [1,6,null,{}]
19:00:01 INFO - TEST-START | test_click.py TestClick.test_input_file
19:00:01 INFO - 1500516001998 Marionette DEBUG Closed connection 382
19:00:02 INFO - 1500516001999 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50309
19:00:02 INFO - 1500516002000 Marionette DEBUG Closed connection 383
19:00:02 INFO - 1500516002001 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50310
19:00:02 INFO - 1500516002003 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:02 INFO - 1500516002003 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002006 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002010 Marionette TRACE 384 <- [1,1,null,{"sessionId":"85f457cb-d228-4dfe-a896-70b785c78b81","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002012 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
19:00:02 INFO - 1500516002012 Marionette TRACE 384 <- [1,2,null,{}]
19:00:02 INFO - 1500516002013 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:00:02 INFO - 1500516002014 Marionette TRACE 384 <- [1,3,null,{}]
19:00:02 INFO - 1500516002015 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
19:00:02 INFO - 1500516002015 Marionette TRACE 384 <- [1,4,null,{}]
19:00:02 INFO - 1500516002017 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}]
19:00:02 INFO - TEST-START: test_click.py TestClick.test_input_file1500516002020 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:00:02 INFO - 1500516002021 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:00:02 INFO - 1500516002023 Marionette TRACE 384 <- [1,6,null,{}]
19:00:02 INFO - 1500516002025 Marionette DEBUG Closed connection 384
19:00:02 INFO - 1500516002026 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50311
19:00:02 INFO - 1500516002027 Marionette DEBUG Closed connection 385
19:00:02 INFO - 1500516002027 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50312
19:00:02 INFO - 1500516002028 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:02 INFO - 1500516002029 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002032 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002035 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d76222d2-cdb5-4f21-b92d-db7dd9c3e5f4","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002037 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
19:00:02 INFO - 1500516002039 Marionette TRACE 386 <- [1,2,null,{}]
19:00:02 INFO - 1500516002041 Marionette DEBUG Closed connection 386
19:00:02 INFO - 1500516002042 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50313
19:00:02 INFO - 1500516002042 Marionette DEBUG Closed connection 387
19:00:02 INFO - 1500516002043 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50314
19:00:02 INFO - 1500516002044 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:00:02 INFO - 1500516002045 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002048 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002051 Marionette TRACE 388 <- [1,1,null,{"sessionId":"988283d7-cd53-4fc3-9868-394ac5c69f76","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002054 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:00:02 INFO - 1500516002055 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"
19:00:02 INFO - 1500516002058 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"
19:00:02 INFO - 1500516002059 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"
19:00:02 INFO - 1500516002073 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:00:02 INFO - 1500516002084 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:00:02 INFO - 1500516002088 Marionette TRACE 388 <- [1,2,null,{}]
19:00:02 INFO - 1500516002090 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:00:02 INFO - 1500516002100 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4d82c37-5b49-4e0c-9ba1-9a0109c89f2a","ELEMENT":"c4d82c37-5b49-4e0c-9ba1-9a0109c89f2a"}}]
19:00:02 INFO - 1500516002101 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c4d82c37-5b49-4e0c-9ba1-9a0109c89f2a"}]
19:00:02 INFO - 1500516002129 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]
19:00:02 INFO - 1500516002132 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}]
19:00:02 INFO - TEST-END: test_click.py TestClick.test_input_file1500516002135 Marionette TRACE 388 <- [1,5,null,{"value":null}]
19:00:02 INFO - 1500516002136 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
19:00:02 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 141ms
19:00:02 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:00:02 INFO - 1500516002138 Marionette TRACE 388 <- [1,6,null,{}]
19:00:02 INFO - 1500516002140 Marionette DEBUG Closed connection 388
19:00:02 INFO - 1500516002141 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50315
19:00:02 INFO - 1500516002142 Marionette DEBUG Closed connection 389
19:00:02 INFO - 1500516002143 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50316
19:00:02 INFO - 1500516002144 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:02 INFO - 1500516002144 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002147 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002150 Marionette TRACE 390 <- [1,1,null,{"sessionId":"6a3b4ab8-8c84-4fa4-8f86-e56d3a33bac6","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002153 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
19:00:02 INFO - 1500516002153 Marionette TRACE 390 <- [1,2,null,{}]
19:00:02 INFO - 1500516002154 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:00:02 INFO - 1500516002155 Marionette TRACE 390 <- [1,3,null,{}]
19:00:02 INFO - 1500516002155 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
19:00:02 INFO - 1500516002156 Marionette TRACE 390 <- [1,4,null,{}]
19:00:02 INFO - 1500516002158 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}]
19:00:02 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500516002161 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:00:02 INFO - 1500516002162 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:00:02 INFO - 1500516002163 Marionette TRACE 390 <- [1,6,null,{}]
19:00:02 INFO - 1500516002165 Marionette DEBUG Closed connection 390
19:00:02 INFO - 1500516002166 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50317
19:00:02 INFO - 1500516002167 Marionette DEBUG Closed connection 391
19:00:02 INFO - 1500516002168 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50318
19:00:02 INFO - 1500516002169 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:02 INFO - 1500516002170 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002172 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002182 Marionette TRACE 392 <- [1,1,null,{"sessionId":"85fb3300-ce7e-4afd-8fd3-aebd447c82ea","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002185 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
19:00:02 INFO - 1500516002187 Marionette TRACE 392 <- [1,2,null,{}]
19:00:02 INFO - 1500516002190 Marionette DEBUG Closed connection 392
19:00:02 INFO - 1500516002190 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50319
19:00:02 INFO - 1500516002191 Marionette DEBUG Closed connection 393
19:00:02 INFO - 1500516002192 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50320
19:00:02 INFO - 1500516002193 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:00:02 INFO - 1500516002194 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002197 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002200 Marionette TRACE 394 <- [1,1,null,{"sessionId":"838f47d9-ceee-4d2d-88ef-f04085a04696","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002202 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"}]
19:00:02 INFO - 1500516002204 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:00:02 INFO - 1500516002206 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:00:02 INFO - 1500516002207 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:00:02 INFO - 1500516002232 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"
19:00:02 INFO - 1500516002234 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"
19:00:02 INFO - 1500516002238 Marionette TRACE 394 <- [1,2,null,{}]
19:00:02 INFO - 1500516002239 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:00:02 INFO - 1500516002249 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a145e0db-a915-45ab-9d89-bfb6476f9e54","ELEMENT":"a145e0db-a915-45ab-9d89-bfb6476f9e54"}}]
19:00:02 INFO - 1500516002254 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:00:02 INFO - 1500516002257 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e50ced4b-2c31-4a23-a509-0fee7bd2e4c3","ELEMENT":"e50ced4b-2c31-4a23-a509-0fee7bd2e4c3"}}]
19:00:02 INFO - 1500516002272 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a145e0db-a915-45ab-9d89-bfb6476f9e54"}]
19:00:02 INFO - 1500516002513 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:00:02 INFO - 1500516002514 Marionette TRACE 394 <- [1,5,null,{}]
19:00:02 INFO - 1500516002515 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"e50ced4b-2c31-4a23-a509-0fee7bd2e4c3"}]
19:00:02 INFO - 1500516002533 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]
19:00:02 INFO - 1500516002535 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
19:00:02 INFO - 1500516002538 Marionette TRACE 394 <- [1,7,null,{"value":false}]
19:00:02 INFO - 1500516002539 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}]
19:00:02 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500516002542 Marionette TRACE 394 <- [1,8,null,{"value":null}]
19:00:02 INFO - 1500516002543 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
19:00:02 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 407ms
19:00:02 INFO - 1500516002545 Marionette TRACE 394 <- [1,9,null,{}]
19:00:02 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:00:02 INFO - 1500516002547 Marionette DEBUG Closed connection 394
19:00:02 INFO - 1500516002549 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50321
19:00:02 INFO - 1500516002549 Marionette DEBUG Closed connection 395
19:00:02 INFO - 1500516002550 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50322
19:00:02 INFO - 1500516002551 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:02 INFO - 1500516002552 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002555 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002558 Marionette TRACE 396 <- [1,1,null,{"sessionId":"535f2c11-8c73-42e7-ac5e-d48c52d24082","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002561 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
19:00:02 INFO - 1500516002561 Marionette TRACE 396 <- [1,2,null,{}]
19:00:02 INFO - 1500516002562 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:00:02 INFO - 1500516002563 Marionette TRACE 396 <- [1,3,null,{}]
19:00:02 INFO - 1500516002564 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
19:00:02 INFO - 1500516002564 Marionette TRACE 396 <- [1,4,null,{}]
19:00:02 INFO - 1500516002566 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}]
19:00:02 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500516002569 Marionette TRACE 396 <- [1,5,null,{"value":null}]
19:00:02 INFO - 1500516002570 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
19:00:02 INFO - 1500516002572 Marionette TRACE 396 <- [1,6,null,{}]
19:00:02 INFO - 1500516002574 Marionette DEBUG Closed connection 396
19:00:02 INFO - 1500516002575 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50323
19:00:02 INFO - 1500516002576 Marionette DEBUG Closed connection 397
19:00:02 INFO - 1500516002576 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50324
19:00:02 INFO - 1500516002578 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:00:02 INFO - 1500516002578 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002581 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002585 Marionette TRACE 398 <- [1,1,null,{"sessionId":"95d49f4e-5d29-43b2-93b0-7241f6092235","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002587 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
19:00:02 INFO - 1500516002588 Marionette TRACE 398 <- [1,2,null,{}]
19:00:02 INFO - 1500516002591 Marionette DEBUG Closed connection 398
19:00:02 INFO - 1500516002591 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50325
19:00:02 INFO - 1500516002592 Marionette DEBUG Closed connection 399
19:00:02 INFO - 1500516002593 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50326
19:00:02 INFO - 1500516002594 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:00:02 INFO - 1500516002595 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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}
19:00:02 INFO - 1500516002598 Marionette DEBUG Register listener.js for window 15
19:00:02 INFO - 1500516002601 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1d90d300-baf5-4640-941f-2cfaa5d9d807","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":1576,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpexcuvl.mozrunner","moz:accessibilityChecks":false}}]
19:00:02 INFO - 1500516002603 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"}]
19:00:02 INFO - 1500516002605 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"
19:00:02 INFO - 1500516002608 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"
19:00:02 INFO - 1500516002608 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"
19:00:02 INFO - 1500516002623 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"
19:00:02 INFO - 1500516002628 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"
19:00:02 INFO - 1500516002640 Marionette TRACE 400 <- [1,2,null,{}]
19:00:02 INFO - 1500516002642 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:00:02 INFO - 1500516002645 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"849a0a78-bb4a-458c-8064-940c5f1fa9c5","ELEMENT":"849a0a78-bb4a-458c-8064-940c5f1fa9c5"}}]
19:00:02 INFO - 1500516002647 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"849a0a78-bb4a-458c-8064-940c5f1fa9c5"}]
19:00:02 INFO - 1500516002670 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
19:00:02 INFO - 1500516002671 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"849a0a78-bb4a-458c-8064-940c5f1fa9c5"}]
19:00:02 INFO - 1500516002690 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element