could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:00:24 INFO - 1498928424329 Marionette TRACE 358 -> [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}]
10:00:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 166ms
10:00:24 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498928424333 Marionette TRACE 358 <- [1,5,null,{"value":null}]
10:00:24 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:00:24 INFO - 1498928424334 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
10:00:24 INFO - 1498928424336 Marionette TRACE 358 <- [1,6,null,{}]
10:00:24 INFO - 1498928424338 Marionette DEBUG Closed connection 358
10:00:24 INFO - 1498928424339 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49887
10:00:24 INFO - 1498928424340 Marionette DEBUG Closed connection 359
10:00:24 INFO - 1498928424341 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49888
10:00:24 INFO - 1498928424342 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:24 INFO - 1498928424343 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:24 INFO - 1498928424346 Marionette DEBUG Register listener.js for window 2147483649
10:00:24 INFO - 1498928424350 Marionette TRACE 360 <- [1,1,null,{"sessionId":"cd161879-3b07-40fb-a6b4-50626de88673","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:24 INFO - 1498928424352 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:00:24 INFO - 1498928424352 Marionette TRACE 360 <- [1,2,null,{}]
10:00:24 INFO - 1498928424353 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:00:24 INFO - 1498928424354 Marionette TRACE 360 <- [1,3,null,{}]
10:00:24 INFO - 1498928424355 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:00:24 INFO - 1498928424356 Marionette TRACE 360 <- [1,4,null,{}]
10:00:24 INFO - 1498928424358 Marionette TRACE 360 -> [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}]
10:00:24 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498928424362 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:00:24 INFO - 1498928424363 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:00:24 INFO - 1498928424365 Marionette TRACE 360 <- [1,6,null,{}]
10:00:24 INFO - 1498928424367 Marionette DEBUG Closed connection 360
10:00:24 INFO - 1498928424368 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49889
10:00:24 INFO - 1498928424368 Marionette DEBUG Closed connection 361
10:00:24 INFO - 1498928424370 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49890
10:00:24 INFO - 1498928424371 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:24 INFO - 1498928424372 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:24 INFO - 1498928424375 Marionette DEBUG Register listener.js for window 2147483649
10:00:24 INFO - 1498928424379 Marionette TRACE 362 <- [1,1,null,{"sessionId":"572b37bd-71b0-4084-a89c-a17964bbaa0a","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:24 INFO - 1498928424380 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:00:24 INFO - 1498928424382 Marionette TRACE 362 <- [1,2,null,{}]
10:00:24 INFO - 1498928424383 Marionette DEBUG Closed connection 362
10:00:24 INFO - 1498928424384 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49891
10:00:24 INFO - 1498928424385 Marionette DEBUG Closed connection 363
10:00:24 INFO - 1498928424386 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49892
10:00:24 INFO - 1498928424387 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:00:24 INFO - 1498928424388 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:24 INFO - 1498928424392 Marionette DEBUG Register listener.js for window 2147483649
10:00:24 INFO - 1498928424395 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a89c78d2-c935-435b-be78-e9681ea56ad3","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:24 INFO - 1498928424397 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49486/clicks.html"}]
10:00:24 INFO - 1498928424399 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49486/hidden.html"
10:00:24 INFO - 1498928424408 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49486/hidden.html"
10:00:24 INFO - 1498928424409 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49486/hidden.html"
10:00:24 INFO - 1498928424429 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49486/clicks.html"
10:00:24 INFO - 1498928424432 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49486/clicks.html"
10:00:24 INFO - 1498928424433 Marionette TRACE 364 <- [1,2,null,{}]
10:00:24 INFO - 1498928424438 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:00:24 INFO - 1498928424446 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2132f356-e490-428d-987d-9aef3a3d7c08","ELEMENT":"2132f356-e490-428d-987d-9aef3a3d7c08"}}]
10:00:24 INFO - 1498928424448 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"2132f356-e490-428d-987d-9aef3a3d7c08"}]
10:00:24 INFO - 1498928424493 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49486/clicks.html"
10:00:24 INFO - 1498928424503 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49486/clicks.html"
10:00:24 INFO - 1498928424503 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49486/clicks.html"
10:00:24 INFO - 1498928424512 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49486/test.html"
10:00:24 INFO - 1498928424517 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49486/test.html"
10:00:24 INFO - 1498928424521 Marionette TRACE 364 <- [1,4,null,{}]
10:00:24 INFO - 1498928424533 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:00:24 INFO - 1498928424537 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d4141e94-2535-4b2e-b004-7b92c1730daf","ELEMENT":"d4141e94-2535-4b2e-b004-7b92c1730daf"}}]
10:00:24 INFO - 1498928424539 Marionette TRACE 364 -> [0,6,"getTitle",{}]
10:00:24 INFO - 1498928424541 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
10:00:24 INFO - 1498928424543 Marionette TRACE 364 -> [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}]
10:00:24 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498928424547 Marionette TRACE 364 <- [1,7,null,{"value":null}]
10:00:24 INFO - 1498928424548 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
10:00:24 INFO - 1498928424550 Marionette TRACE 364 <- [1,8,null,{}]
10:00:24 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 213ms
10:00:24 INFO - TEST-START | test_click.py TestClick.test_container_element
10:00:24 INFO - 1498928424552 Marionette DEBUG Closed connection 364
10:00:24 INFO - 1498928424553 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49895
10:00:24 INFO - 1498928424554 Marionette DEBUG Closed connection 365
10:00:24 INFO - 1498928424554 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49896
10:00:24 INFO - 1498928424555 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:24 INFO - 1498928424556 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:24 INFO - 1498928424559 Marionette DEBUG Register listener.js for window 2147483649
10:00:24 INFO - 1498928424564 Marionette TRACE 366 <- [1,1,null,{"sessionId":"7cd8a567-0b23-493f-96b5-a075a6ec956b","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:24 INFO - 1498928424565 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:00:24 INFO - 1498928424566 Marionette TRACE 366 <- [1,2,null,{}]
10:00:24 INFO - 1498928424567 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:00:24 INFO - 1498928424567 Marionette TRACE 366 <- [1,3,null,{}]
10:00:24 INFO - 1498928424568 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:00:24 INFO - 1498928424569 Marionette TRACE 366 <- [1,4,null,{}]
10:00:24 INFO - 1498928424571 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')","sandbox":"simpletest","line":316}]
10:00:24 INFO - TEST-START: test_click.py TestClick.test_container_element1498928424574 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:00:24 INFO - 1498928424575 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:00:24 INFO - 1498928424577 Marionette TRACE 366 <- [1,6,null,{}]
10:00:24 INFO - 1498928424579 Marionette DEBUG Closed connection 366
10:00:24 INFO - 1498928424580 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49897
10:00:24 INFO - 1498928424582 Marionette DEBUG Closed connection 367
10:00:24 INFO - 1498928424582 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49898
10:00:24 INFO - 1498928424584 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:24 INFO - 1498928424585 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:24 INFO - 1498928424590 Marionette DEBUG Register listener.js for window 2147483649
10:00:24 INFO - 1498928424594 Marionette TRACE 368 <- [1,1,null,{"sessionId":"368048b5-0a15-4021-87c7-a1dcceac6c55","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:24 INFO - 1498928424596 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:00:24 INFO - 1498928424598 Marionette TRACE 368 <- [1,2,null,{}]
10:00:24 INFO - 1498928424599 Marionette DEBUG Closed connection 368
10:00:24 INFO - 1498928424601 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49899
10:00:24 INFO - 1498928424602 Marionette DEBUG Closed connection 369
10:00:24 INFO - 1498928424603 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49900
10:00:24 INFO - 1498928424604 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:00:24 INFO - 1498928424605 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:24 INFO - 1498928424608 Marionette DEBUG Register listener.js for window 2147483649
10:00:24 INFO - 1498928424612 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a276d063-7c66-40ae-b6d8-3e5561ec6aea","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:24 INFO - 1498928424613 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%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"}]
10:00:24 INFO - 1498928424616 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49486/test.html"
10:00:24 INFO - 1498928424618 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49486/test.html"
10:00:24 INFO - 1498928424618 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49486/test.html"
10:00:24 INFO - 1498928424631 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"
10:00:24 INFO - 1498928424632 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"
10:00:24 INFO - 1498928424644 Marionette TRACE 370 <- [1,2,null,{}]
10:00:24 INFO - 1498928424650 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:00:24 INFO - 1498928424654 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3a70ee2a-f782-4537-9200-86da47d1648d","ELEMENT":"3a70ee2a-f782-4537-9200-86da47d1648d"}}]
10:00:24 INFO - 1498928424655 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"3a70ee2a-f782-4537-9200-86da47d1648d"}]
10:00:24 INFO - 1498928424902 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:00:24 INFO - 1498928424903 Marionette TRACE 370 <- [1,4,null,{}]
10:00:24 INFO - 1498928424905 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"3a70ee2a-f782-4537-9200-86da47d1648d","name":"selected"}]
10:00:24 INFO - 1498928424910 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:00:24 INFO - 1498928424912 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')","sandbox":"simpletest","line":328}]
10:00:24 INFO - TEST-END: test_click.py TestClick.test_container_element1498928424916 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:00:24 INFO - 1498928424917 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:00:24 INFO - 1498928424919 Marionette TRACE 370 <- [1,7,null,{}]
10:00:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
10:00:24 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:00:24 INFO - 1498928424921 Marionette DEBUG Closed connection 370
10:00:24 INFO - 1498928424922 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49901
10:00:24 INFO - 1498928424922 Marionette DEBUG Closed connection 371
10:00:24 INFO - 1498928424923 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49902
10:00:24 INFO - 1498928424924 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:24 INFO - 1498928424925 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:24 INFO - 1498928424928 Marionette DEBUG Register listener.js for window 2147483649
10:00:24 INFO - 1498928424932 Marionette TRACE 372 <- [1,1,null,{"sessionId":"f62777b6-5c0d-42ec-ac30-3365d57dd5ec","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:24 INFO - 1498928424933 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:00:24 INFO - 1498928424934 Marionette TRACE 372 <- [1,2,null,{}]
10:00:24 INFO - 1498928424935 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:00:24 INFO - 1498928424935 Marionette TRACE 372 <- [1,3,null,{}]
10:00:24 INFO - 1498928424936 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:00:24 INFO - 1498928424937 Marionette TRACE 372 <- [1,4,null,{}]
10:00:24 INFO - 1498928424939 Marionette TRACE 372 -> [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}]
10:00:24 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498928424943 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:00:24 INFO - 1498928424944 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:00:24 INFO - 1498928424946 Marionette TRACE 372 <- [1,6,null,{}]
10:00:24 INFO - 1498928424948 Marionette DEBUG Closed connection 372
10:00:24 INFO - 1498928424949 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49903
10:00:24 INFO - 1498928424949 Marionette DEBUG Closed connection 373
10:00:25 INFO - 1498928424950 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49904
10:00:25 INFO - 1498928424951 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:25 INFO - 1498928424952 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:25 INFO - 1498928424955 Marionette DEBUG Register listener.js for window 2147483649
10:00:25 INFO - 1498928424959 Marionette TRACE 374 <- [1,1,null,{"sessionId":"80497615-4379-466d-99ec-f94c40080ac9","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:25 INFO - 1498928424960 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:00:25 INFO - 1498928424962 Marionette TRACE 374 <- [1,2,null,{}]
10:00:25 INFO - 1498928424963 Marionette DEBUG Closed connection 374
10:00:25 INFO - 1498928424964 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49905
10:00:25 INFO - 1498928424965 Marionette DEBUG Closed connection 375
10:00:25 INFO - 1498928424966 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49906
10:00:25 INFO - 1498928424967 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:00:25 INFO - 1498928424968 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:25 INFO - 1498928424979 Marionette DEBUG Register listener.js for window 2147483649
10:00:25 INFO - 1498928424982 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e928fbb5-c9d0-4466-ab6b-d6426c76ef20","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:25 INFO - 1498928424984 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%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"}]
10:00:25 INFO - 1498928424986 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"
10:00:25 INFO - 1498928424989 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"
10:00:25 INFO - 1498928424989 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"
10:00:25 INFO - 1498928424996 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"
10:00:25 INFO - 1498928425001 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"
10:00:25 INFO - 1498928425006 Marionette TRACE 376 <- [1,2,null,{}]
10:00:25 INFO - 1498928425018 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:00:25 INFO - 1498928425022 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"60e47506-3b01-482f-8392-1e16736d96c7","ELEMENT":"60e47506-3b01-482f-8392-1e16736d96c7"}}]
10:00:25 INFO - 1498928425023 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"60e47506-3b01-482f-8392-1e16736d96c7"}]
10:00:25 INFO - 1498928425269 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:00:25 INFO - 1498928425271 Marionette TRACE 376 <- [1,4,null,{}]
10:00:25 INFO - 1498928425272 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"60e47506-3b01-482f-8392-1e16736d96c7","name":"selected"}]
10:00:25 INFO - 1498928425277 Marionette TRACE 376 <- [1,5,null,{"value":true}]
10:00:25 INFO - 1498928425279 Marionette TRACE 376 -> [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}]
10:00:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 366ms
10:00:25 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:00:25 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498928425282 Marionette TRACE 376 <- [1,6,null,{"value":null}]
10:00:25 INFO - 1498928425283 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
10:00:25 INFO - 1498928425285 Marionette TRACE 376 <- [1,7,null,{}]
10:00:25 INFO - 1498928425287 Marionette DEBUG Closed connection 376
10:00:25 INFO - 1498928425288 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49907
10:00:25 INFO - 1498928425289 Marionette DEBUG Closed connection 377
10:00:25 INFO - 1498928425290 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49908
10:00:25 INFO - 1498928425291 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:25 INFO - 1498928425292 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:25 INFO - 1498928425295 Marionette DEBUG Register listener.js for window 2147483649
10:00:25 INFO - 1498928425298 Marionette TRACE 378 <- [1,1,null,{"sessionId":"cbbb4810-118e-4b2e-a510-9d2f1f43aaec","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:25 INFO - 1498928425300 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:00:25 INFO - 1498928425300 Marionette TRACE 378 <- [1,2,null,{}]
10:00:25 INFO - 1498928425301 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:00:25 INFO - 1498928425302 Marionette TRACE 378 <- [1,3,null,{}]
10:00:25 INFO - 1498928425303 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:00:25 INFO - 1498928425303 Marionette TRACE 378 <- [1,4,null,{}]
10:00:25 INFO - 1498928425305 Marionette TRACE 378 -> [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}]
10:00:25 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498928425310 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:00:25 INFO - 1498928425311 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:00:25 INFO - 1498928425312 Marionette TRACE 378 <- [1,6,null,{}]
10:00:25 INFO - 1498928425314 Marionette DEBUG Closed connection 378
10:00:25 INFO - 1498928425315 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49909
10:00:25 INFO - 1498928425316 Marionette DEBUG Closed connection 379
10:00:25 INFO - 1498928425317 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49910
10:00:25 INFO - 1498928425318 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:25 INFO - 1498928425319 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:25 INFO - 1498928425322 Marionette DEBUG Register listener.js for window 2147483649
10:00:25 INFO - 1498928425325 Marionette TRACE 380 <- [1,1,null,{"sessionId":"433fa652-2a0b-4c06-9923-d167e7bec1d4","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:25 INFO - 1498928425327 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:00:25 INFO - 1498928425328 Marionette TRACE 380 <- [1,2,null,{}]
10:00:25 INFO - 1498928425330 Marionette DEBUG Closed connection 380
10:00:25 INFO - 1498928425331 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49911
10:00:25 INFO - 1498928425332 Marionette DEBUG Closed connection 381
10:00:25 INFO - 1498928425333 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49912
10:00:25 INFO - 1498928425334 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:00:25 INFO - 1498928425335 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:25 INFO - 1498928425338 Marionette DEBUG Register listener.js for window 2147483649
10:00:25 INFO - 1498928425341 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ea968ab6-5f20-4dad-bc03-2d37c648fe14","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:25 INFO - 1498928425343 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%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"}]
10:00:25 INFO - 1498928425345 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"
10:00:25 INFO - 1498928425347 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"
10:00:25 INFO - 1498928425348 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"
10:00:25 INFO - 1498928425355 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"
10:00:25 INFO - 1498928425358 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"
10:00:25 INFO - 1498928425369 Marionette TRACE 382 <- [1,2,null,{}]
10:00:25 INFO - 1498928425381 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:00:25 INFO - 1498928425385 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eda0a3f5-33d5-48b4-a5a7-1eae1c37cd27","ELEMENT":"eda0a3f5-33d5-48b4-a5a7-1eae1c37cd27"}}]
10:00:25 INFO - 1498928425386 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"eda0a3f5-33d5-48b4-a5a7-1eae1c37cd27"}]
10:00:25 INFO - 1498928425634 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:00:25 INFO - 1498928425635 Marionette TRACE 382 <- [1,4,null,{}]
10:00:25 INFO - 1498928425638 Marionette TRACE 382 -> [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}]
10:00:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498928425641 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:00:25 INFO - 1498928425642 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:00:25 INFO - 1498928425644 Marionette TRACE 382 <- [1,6,null,{}]
10:00:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 359ms
10:00:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:00:25 INFO - 1498928425646 Marionette DEBUG Closed connection 382
10:00:25 INFO - 1498928425646 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49913
10:00:25 INFO - 1498928425647 Marionette DEBUG Closed connection 383
10:00:25 INFO - 1498928425648 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49914
10:00:25 INFO - 1498928425649 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:25 INFO - 1498928425650 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:25 INFO - 1498928425653 Marionette DEBUG Register listener.js for window 2147483649
10:00:25 INFO - 1498928425657 Marionette TRACE 384 <- [1,1,null,{"sessionId":"736031a4-e2e0-4318-ad3f-99e01a41e407","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:25 INFO - 1498928425658 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:00:25 INFO - 1498928425659 Marionette TRACE 384 <- [1,2,null,{}]
10:00:25 INFO - 1498928425660 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:00:25 INFO - 1498928425660 Marionette TRACE 384 <- [1,3,null,{}]
10:00:25 INFO - 1498928425661 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:00:25 INFO - 1498928425662 Marionette TRACE 384 <- [1,4,null,{}]
10:00:25 INFO - 1498928425664 Marionette TRACE 384 -> [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}]
10:00:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498928425667 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:00:25 INFO - 1498928425668 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:00:25 INFO - 1498928425670 Marionette TRACE 384 <- [1,6,null,{}]
10:00:25 INFO - 1498928425672 Marionette DEBUG Closed connection 384
10:00:25 INFO - 1498928425672 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49915
10:00:25 INFO - 1498928425673 Marionette DEBUG Closed connection 385
10:00:25 INFO - 1498928425674 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49916
10:00:25 INFO - 1498928425675 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:25 INFO - 1498928425676 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:25 INFO - 1498928425679 Marionette DEBUG Register listener.js for window 2147483649
10:00:25 INFO - 1498928425682 Marionette TRACE 386 <- [1,1,null,{"sessionId":"11ad8b2e-7390-42ad-b0e7-075e680b52ad","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:25 INFO - 1498928425684 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:00:25 INFO - 1498928425685 Marionette TRACE 386 <- [1,2,null,{}]
10:00:25 INFO - 1498928425687 Marionette DEBUG Closed connection 386
10:00:25 INFO - 1498928425688 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49917
10:00:25 INFO - 1498928425689 Marionette DEBUG Closed connection 387
10:00:25 INFO - 1498928425690 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49918
10:00:25 INFO - 1498928425691 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:00:25 INFO - 1498928425692 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:25 INFO - 1498928425695 Marionette DEBUG Register listener.js for window 2147483649
10:00:25 INFO - 1498928425698 Marionette TRACE 388 <- [1,1,null,{"sessionId":"20eb73c6-2f98-42dc-af0e-60d70ba23dbb","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:25 INFO - 1498928425701 Marionette TRACE 388 -> [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"}]
10:00:25 INFO - 1498928425703 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"
10:00:25 INFO - 1498928425705 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"
10:00:25 INFO - 1498928425705 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"
10:00:25 INFO - 1498928425713 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"
10:00:25 INFO - 1498928425717 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"
10:00:25 INFO - 1498928425723 Marionette TRACE 388 <- [1,2,null,{}]
10:00:25 INFO - 1498928425733 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:00:25 INFO - 1498928425739 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ecd5c2b1-b96f-43e9-bfd3-b19ecc1ca4e6","ELEMENT":"ecd5c2b1-b96f-43e9-bfd3-b19ecc1ca4e6"}}]
10:00:25 INFO - 1498928425740 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"ecd5c2b1-b96f-43e9-bfd3-b19ecc1ca4e6"}]
10:00:25 INFO - 1498928425987 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:00:25 INFO - 1498928425988 Marionette TRACE 388 <- [1,4,null,{}]
10:00:25 INFO - 1498928425991 Marionette TRACE 388 -> [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}]
10:00:26 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498928425998 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:00:26 INFO - 1498928426000 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:00:26 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 357ms
10:00:26 INFO - TEST-START | test_click.py TestClick.test_input_file
10:00:26 INFO - 1498928426001 Marionette TRACE 388 <- [1,6,null,{}]
10:00:26 INFO - 1498928426003 Marionette DEBUG Closed connection 388
10:00:26 INFO - 1498928426004 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49919
10:00:26 INFO - 1498928426005 Marionette DEBUG Closed connection 389
10:00:26 INFO - 1498928426006 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49920
10:00:26 INFO - 1498928426007 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:26 INFO - 1498928426009 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426012 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426015 Marionette TRACE 390 <- [1,1,null,{"sessionId":"ae5ca90c-e886-4b66-97a6-09b323642ac3","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426017 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:00:26 INFO - 1498928426018 Marionette TRACE 390 <- [1,2,null,{}]
10:00:26 INFO - 1498928426019 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:00:26 INFO - 1498928426020 Marionette TRACE 390 <- [1,3,null,{}]
10:00:26 INFO - 1498928426022 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:00:26 INFO - 1498928426023 Marionette TRACE 390 <- [1,4,null,{}]
10:00:26 INFO - 1498928426025 Marionette TRACE 390 -> [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}]
10:00:26 INFO - TEST-START: test_click.py TestClick.test_input_file1498928426028 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:00:26 INFO - 1498928426030 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:00:26 INFO - 1498928426031 Marionette TRACE 390 <- [1,6,null,{}]
10:00:26 INFO - 1498928426033 Marionette DEBUG Closed connection 390
10:00:26 INFO - 1498928426034 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49921
10:00:26 INFO - 1498928426035 Marionette DEBUG Closed connection 391
10:00:26 INFO - 1498928426036 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49922
10:00:26 INFO - 1498928426037 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:26 INFO - 1498928426038 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426041 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426044 Marionette TRACE 392 <- [1,1,null,{"sessionId":"15f0390e-3fe9-4cdd-8c25-f605ddf80ed8","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426046 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:00:26 INFO - 1498928426047 Marionette TRACE 392 <- [1,2,null,{}]
10:00:26 INFO - 1498928426050 Marionette DEBUG Closed connection 392
10:00:26 INFO - 1498928426050 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49923
10:00:26 INFO - 1498928426051 Marionette DEBUG Closed connection 393
10:00:26 INFO - 1498928426052 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49924
10:00:26 INFO - 1498928426053 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:00:26 INFO - 1498928426054 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426057 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426070 Marionette TRACE 394 <- [1,1,null,{"sessionId":"96fa214b-3a1b-42e6-8c05-34be5694b53c","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426071 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:00:26 INFO - 1498928426073 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"
10:00:26 INFO - 1498928426078 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"
10:00:26 INFO - 1498928426079 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"
10:00:26 INFO - 1498928426088 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:00:26 INFO - 1498928426096 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:00:26 INFO - 1498928426105 Marionette TRACE 394 <- [1,2,null,{}]
10:00:26 INFO - 1498928426115 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:00:26 INFO - 1498928426119 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"417a0df9-c263-4f68-bb7b-6e0af3ec2d50","ELEMENT":"417a0df9-c263-4f68-bb7b-6e0af3ec2d50"}}]
10:00:26 INFO - 1498928426120 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"417a0df9-c263-4f68-bb7b-6e0af3ec2d50"}]
10:00:26 INFO - 1498928426148 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:00:26 INFO - 1498928426150 Marionette TRACE 394 -> [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}]
10:00:26 INFO - TEST-END: test_click.py TestClick.test_input_file1498928426153 Marionette TRACE 394 <- [1,5,null,{"value":null}]
10:00:26 INFO - 1498928426154 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
10:00:26 INFO - 1498928426156 Marionette TRACE 394 <- [1,6,null,{}]
10:00:26 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 154ms
10:00:26 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:00:26 INFO - 1498928426158 Marionette DEBUG Closed connection 394
10:00:26 INFO - 1498928426159 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49925
10:00:26 INFO - 1498928426160 Marionette DEBUG Closed connection 395
10:00:26 INFO - 1498928426160 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49926
10:00:26 INFO - 1498928426162 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:26 INFO - 1498928426162 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426165 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426169 Marionette TRACE 396 <- [1,1,null,{"sessionId":"c6fdaf45-48aa-4892-8c4e-c71f4c360f16","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426170 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:00:26 INFO - 1498928426171 Marionette TRACE 396 <- [1,2,null,{}]
10:00:26 INFO - 1498928426172 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:00:26 INFO - 1498928426172 Marionette TRACE 396 <- [1,3,null,{}]
10:00:26 INFO - 1498928426173 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:00:26 INFO - 1498928426174 Marionette TRACE 396 <- [1,4,null,{}]
10:00:26 INFO - 1498928426176 Marionette TRACE 396 -> [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}]
10:00:26 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498928426179 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:00:26 INFO - 1498928426180 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:00:26 INFO - 1498928426182 Marionette TRACE 396 <- [1,6,null,{}]
10:00:26 INFO - 1498928426183 Marionette DEBUG Closed connection 396
10:00:26 INFO - 1498928426184 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49927
10:00:26 INFO - 1498928426185 Marionette DEBUG Closed connection 397
10:00:26 INFO - 1498928426186 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49928
10:00:26 INFO - 1498928426187 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:26 INFO - 1498928426188 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426191 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426194 Marionette TRACE 398 <- [1,1,null,{"sessionId":"df2cf1c1-aa59-45cd-8a12-8782209874a3","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426196 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:00:26 INFO - 1498928426197 Marionette TRACE 398 <- [1,2,null,{}]
10:00:26 INFO - 1498928426199 Marionette DEBUG Closed connection 398
10:00:26 INFO - 1498928426200 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49929
10:00:26 INFO - 1498928426201 Marionette DEBUG Closed connection 399
10:00:26 INFO - 1498928426202 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49930
10:00:26 INFO - 1498928426203 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:00:26 INFO - 1498928426204 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426207 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426210 Marionette TRACE 400 <- [1,1,null,{"sessionId":"398042a8-f014-4bed-8739-868caf95a9a0","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426211 Marionette TRACE 400 -> [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"}]
10:00:26 INFO - 1498928426213 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:00:26 INFO - 1498928426216 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:00:26 INFO - 1498928426216 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:00:26 INFO - 1498928426234 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"
10:00:26 INFO - 1498928426236 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"
10:00:26 INFO - 1498928426251 Marionette TRACE 400 <- [1,2,null,{}]
10:00:26 INFO - 1498928426259 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:00:26 INFO - 1498928426268 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aac62464-d10d-4a1c-bafd-4c386cd15f24","ELEMENT":"aac62464-d10d-4a1c-bafd-4c386cd15f24"}}]
10:00:26 INFO - 1498928426269 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:00:26 INFO - 1498928426272 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f54fbf4-01b9-49a0-b26d-c588f0db182a","ELEMENT":"2f54fbf4-01b9-49a0-b26d-c588f0db182a"}}]
10:00:26 INFO - 1498928426273 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"aac62464-d10d-4a1c-bafd-4c386cd15f24"}]
10:00:26 INFO - 1498928426520 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:00:26 INFO - 1498928426521 Marionette TRACE 400 <- [1,5,null,{}]
10:00:26 INFO - 1498928426523 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"2f54fbf4-01b9-49a0-b26d-c588f0db182a"}]
10:00:26 INFO - 1498928426541 Marionette TRACE 400 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:00:26 INFO - 1498928426543 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:00:26 INFO - 1498928426547 Marionette TRACE 400 <- [1,7,null,{"value":false}]
10:00:26 INFO - 1498928426548 Marionette TRACE 400 -> [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}]
10:00:26 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498928426552 Marionette TRACE 400 <- [1,8,null,{"value":null}]
10:00:26 INFO - 1498928426553 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
10:00:26 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 398ms
10:00:26 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:00:26 INFO - 1498928426555 Marionette TRACE 400 <- [1,9,null,{}]
10:00:26 INFO - 1498928426556 Marionette DEBUG Closed connection 400
10:00:26 INFO - 1498928426557 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49931
10:00:26 INFO - 1498928426558 Marionette DEBUG Closed connection 401
10:00:26 INFO - 1498928426559 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49932
10:00:26 INFO - 1498928426560 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:26 INFO - 1498928426561 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426564 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426567 Marionette TRACE 402 <- [1,1,null,{"sessionId":"2395aa93-f29b-4f67-8932-77aa3c8ddffd","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426569 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
10:00:26 INFO - 1498928426570 Marionette TRACE 402 <- [1,2,null,{}]
10:00:26 INFO - 1498928426571 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:00:26 INFO - 1498928426571 Marionette TRACE 402 <- [1,3,null,{}]
10:00:26 INFO - 1498928426572 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
10:00:26 INFO - 1498928426573 Marionette TRACE 402 <- [1,4,null,{}]
10:00:26 INFO - 1498928426575 Marionette TRACE 402 -> [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}]
10:00:26 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498928426578 Marionette TRACE 402 <- [1,5,null,{"value":null}]
10:00:26 INFO - 1498928426579 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
10:00:26 INFO - 1498928426580 Marionette TRACE 402 <- [1,6,null,{}]
10:00:26 INFO - 1498928426582 Marionette DEBUG Closed connection 402
10:00:26 INFO - 1498928426583 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49933
10:00:26 INFO - 1498928426584 Marionette DEBUG Closed connection 403
10:00:26 INFO - 1498928426585 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49934
10:00:26 INFO - 1498928426586 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:00:26 INFO - 1498928426586 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426589 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426593 Marionette TRACE 404 <- [1,1,null,{"sessionId":"864922e5-4a9f-4b0e-9e06-43ba95daa403","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426594 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
10:00:26 INFO - 1498928426596 Marionette TRACE 404 <- [1,2,null,{}]
10:00:26 INFO - 1498928426598 Marionette DEBUG Closed connection 404
10:00:26 INFO - 1498928426598 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49935
10:00:26 INFO - 1498928426599 Marionette DEBUG Closed connection 405
10:00:26 INFO - 1498928426600 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49936
10:00:26 INFO - 1498928426601 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:00:26 INFO - 1498928426602 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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}
10:00:26 INFO - 1498928426605 Marionette DEBUG Register listener.js for window 2147483649
10:00:26 INFO - 1498928426608 Marionette TRACE 406 <- [1,1,null,{"sessionId":"fa83001d-fd6f-4217-afb5-1fd2db5cb3d9","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":1320,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpm09jue.mozrunner","moz:accessibilityChecks":false}}]
10:00:26 INFO - 1498928426610 Marionette TRACE 406 -> [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"}]
10:00:26 INFO - 1498928426612 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"
10:00:26 INFO - 1498928426614 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"
10:00:26 INFO - 1498928426615 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"
10:00:26 INFO - 1498928426622 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"
10:00:26 INFO - 1498928426626 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"
10:00:26 INFO - 1498928426636 Marionette TRACE 406 <- [1,2,null,{}]
10:00:26 INFO - 1498928426647 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:00:26 INFO - 1498928426651 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"998b0b3a-ac0b-4708-8967-d316dba6ed69","ELEMENT":"998b0b3a-ac0b-4708-8967-d316dba6ed69"}}]
10:00:26 INFO - 1498928426652 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"998b0b3a-ac0b-4708-8967-d316dba6ed69"}]
10:00:26 INFO - 1498928426676 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
10:00:26 INFO - 1498928426678 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"998b0b3a-ac0b-4708-8967-d316dba6ed69"}]
10:00:26 INFO - 1498928426698 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element