could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementNotInteractableError@chrome://marionette/content/error.js:311:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:176: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
15:25:49 INFO - 1498775149486 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}]
15:25:49 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498775149488 Marionette TRACE 358 <- [1,5,null,{"value":null}]
15:25:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 111ms
15:25:49 INFO - 1498775149489 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
15:25:49 INFO - 1498775149491 Marionette TRACE 358 <- [1,6,null,{}]
15:25:49 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:25:49 INFO - 1498775149492 Marionette DEBUG Closed connection 358
15:25:49 INFO - 1498775149493 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49731
15:25:49 INFO - 1498775149493 Marionette DEBUG Closed connection 359
15:25:49 INFO - 1498775149494 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49732
15:25:49 INFO - 1498775149495 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:49 INFO - 1498775149496 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:49 INFO - 1498775149498 Marionette DEBUG Register listener.js for window 2147483649
15:25:49 INFO - 1498775149501 Marionette TRACE 360 <- [1,1,null,{"sessionId":"fec1949d-1c23-4503-8e57-d0bb9a921c76","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:49 INFO - 1498775149502 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
15:25:49 INFO - 1498775149502 Marionette TRACE 360 <- [1,2,null,{}]
15:25:49 INFO - 1498775149503 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:25:49 INFO - 1498775149503 Marionette TRACE 360 <- [1,3,null,{}]
15:25:49 INFO - 1498775149504 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
15:25:49 INFO - 1498775149504 Marionette TRACE 360 <- [1,4,null,{}]
15:25:49 INFO - 1498775149506 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}]
15:25:49 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498775149508 Marionette TRACE 360 <- [1,5,null,{"value":null}]
15:25:49 INFO - 1498775149509 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
15:25:49 INFO - 1498775149510 Marionette TRACE 360 <- [1,6,null,{}]
15:25:49 INFO - 1498775149511 Marionette DEBUG Closed connection 360
15:25:49 INFO - 1498775149512 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49733
15:25:49 INFO - 1498775149513 Marionette DEBUG Closed connection 361
15:25:49 INFO - 1498775149513 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49734
15:25:49 INFO - 1498775149514 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:49 INFO - 1498775149515 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:49 INFO - 1498775149517 Marionette DEBUG Register listener.js for window 2147483649
15:25:49 INFO - 1498775149519 Marionette TRACE 362 <- [1,1,null,{"sessionId":"eb79e81f-8c66-4ed9-a377-f64d041ca3ff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:49 INFO - 1498775149520 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
15:25:49 INFO - 1498775149521 Marionette TRACE 362 <- [1,2,null,{}]
15:25:49 INFO - 1498775149522 Marionette DEBUG Closed connection 362
15:25:49 INFO - 1498775149523 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49735
15:25:49 INFO - 1498775149524 Marionette DEBUG Closed connection 363
15:25:49 INFO - 1498775149524 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49736
15:25:49 INFO - 1498775149525 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:25:49 INFO - 1498775149525 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:49 INFO - 1498775149528 Marionette DEBUG Register listener.js for window 2147483649
15:25:49 INFO - 1498775149530 Marionette TRACE 364 <- [1,1,null,{"sessionId":"7c75968f-0a90-4663-a311-75b2008a3c87","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:49 INFO - 1498775149531 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49336/clicks.html"}]
15:25:49 INFO - 1498775149533 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/hidden.html"
15:25:49 INFO - 1498775149539 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/hidden.html"
15:25:49 INFO - 1498775149540 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/hidden.html"
15:25:49 INFO - 1498775149545 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/clicks.html"
15:25:49 INFO - 1498775149550 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/clicks.html"
15:25:49 INFO - 1498775149552 Marionette TRACE 364 <- [1,2,null,{}]
15:25:49 INFO - 1498775149555 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:25:49 INFO - 1498775149557 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6bca8519-431f-4bf2-86fe-dffc41f8df9c","ELEMENT":"6bca8519-431f-4bf2-86fe-dffc41f8df9c"}}]
15:25:49 INFO - 1498775149558 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6bca8519-431f-4bf2-86fe-dffc41f8df9c"}]
15:25:49 INFO - 1498775149596 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/clicks.html"
15:25:49 INFO - 1498775149603 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/clicks.html"
15:25:49 INFO - 1498775149603 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/clicks.html"
15:25:49 INFO - 1498775149613 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/test.html"
15:25:49 INFO - 1498775149616 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/test.html"
15:25:49 INFO - 1498775149620 Marionette TRACE 364 <- [1,4,null,{}]
15:25:49 INFO - 1498775149623 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:25:49 INFO - 1498775149625 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"de40d997-6085-4aac-a19c-26644e390c72","ELEMENT":"de40d997-6085-4aac-a19c-26644e390c72"}}]
15:25:49 INFO - 1498775149626 Marionette TRACE 364 -> [0,6,"getTitle",{}]
15:25:49 INFO - 1498775149631 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
15:25:49 INFO - 1498775149632 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}]
15:25:49 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498775149643 Marionette TRACE 364 <- [1,7,null,{"value":null}]
15:25:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 154ms
15:25:49 INFO - 1498775149644 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
15:25:49 INFO - 1498775149646 Marionette TRACE 364 <- [1,8,null,{}]
15:25:49 INFO - TEST-START | test_click.py TestClick.test_container_element
15:25:49 INFO - 1498775149647 Marionette DEBUG Closed connection 364
15:25:49 INFO - 1498775149648 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49739
15:25:49 INFO - 1498775149649 Marionette DEBUG Closed connection 365
15:25:49 INFO - 1498775149649 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49740
15:25:49 INFO - 1498775149650 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:49 INFO - 1498775149651 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:49 INFO - 1498775149659 Marionette DEBUG Register listener.js for window 2147483649
15:25:49 INFO - 1498775149662 Marionette TRACE 366 <- [1,1,null,{"sessionId":"8fba4e1e-1a76-427a-9e80-368ec662eb1b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:49 INFO - 1498775149663 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
15:25:49 INFO - 1498775149664 Marionette TRACE 366 <- [1,2,null,{}]
15:25:49 INFO - 1498775149665 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:25:49 INFO - 1498775149665 Marionette TRACE 366 <- [1,3,null,{}]
15:25:49 INFO - 1498775149666 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
15:25:49 INFO - 1498775149666 Marionette TRACE 366 <- [1,4,null,{}]
15:25:49 INFO - 1498775149667 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}]
15:25:49 INFO - TEST-START: test_click.py TestClick.test_container_element1498775149670 Marionette TRACE 366 <- [1,5,null,{"value":null}]
15:25:49 INFO - 1498775149670 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
15:25:49 INFO - 1498775149672 Marionette TRACE 366 <- [1,6,null,{}]
15:25:49 INFO - 1498775149673 Marionette DEBUG Closed connection 366
15:25:49 INFO - 1498775149673 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49741
15:25:49 INFO - 1498775149674 Marionette DEBUG Closed connection 367
15:25:49 INFO - 1498775149675 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49742
15:25:49 INFO - 1498775149676 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:49 INFO - 1498775149676 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:49 INFO - 1498775149684 Marionette DEBUG Register listener.js for window 2147483649
15:25:49 INFO - 1498775149687 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b682153a-65ea-484e-8c89-a653b95697d4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:49 INFO - 1498775149688 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
15:25:49 INFO - 1498775149689 Marionette TRACE 368 <- [1,2,null,{}]
15:25:49 INFO - 1498775149690 Marionette DEBUG Closed connection 368
15:25:49 INFO - 1498775149691 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49743
15:25:49 INFO - 1498775149692 Marionette DEBUG Closed connection 369
15:25:49 INFO - 1498775149692 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49744
15:25:49 INFO - 1498775149693 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:25:49 INFO - 1498775149694 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:49 INFO - 1498775149696 Marionette DEBUG Register listener.js for window 2147483649
15:25:49 INFO - 1498775149707 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d7603092-cd1b-4b1c-b90b-eb69a2adc6d9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:49 INFO - 1498775149708 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"}]
15:25:49 INFO - 1498775149722 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/test.html"
15:25:49 INFO - 1498775149724 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/test.html"
15:25:49 INFO - 1498775149724 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/test.html"
15:25:49 INFO - 1498775149729 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"
15:25:49 INFO - 1498775149733 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"
15:25:49 INFO - 1498775149738 Marionette TRACE 370 <- [1,2,null,{}]
15:25:49 INFO - 1498775149743 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:25:49 INFO - 1498775149749 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"055c9557-edf5-4013-81ed-85bef4ce3635","ELEMENT":"055c9557-edf5-4013-81ed-85bef4ce3635"}}]
15:25:49 INFO - 1498775149752 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"055c9557-edf5-4013-81ed-85bef4ce3635"}]
15:25:49 INFO - 1498775149990 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:25:49 INFO - 1498775149991 Marionette TRACE 370 <- [1,4,null,{}]
15:25:49 INFO - 1498775149992 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"055c9557-edf5-4013-81ed-85bef4ce3635","name":"selected"}]
15:25:50 INFO - 1498775149997 Marionette TRACE 370 <- [1,5,null,{"value":true}]
15:25:50 INFO - 1498775149998 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}]
15:25:50 INFO - TEST-END: test_click.py TestClick.test_container_element1498775150000 Marionette TRACE 370 <- [1,6,null,{"value":null}]
15:25:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 356ms
15:25:50 INFO - 1498775150001 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
15:25:50 INFO - 1498775150002 Marionette TRACE 370 <- [1,7,null,{}]
15:25:50 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:25:50 INFO - 1498775150004 Marionette DEBUG Closed connection 370
15:25:50 INFO - 1498775150005 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49745
15:25:50 INFO - 1498775150006 Marionette DEBUG Closed connection 371
15:25:50 INFO - 1498775150006 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49746
15:25:50 INFO - 1498775150007 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:50 INFO - 1498775150008 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150010 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150013 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ad7db20f-2788-435d-842a-6e7408cd9851","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150014 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
15:25:50 INFO - 1498775150014 Marionette TRACE 372 <- [1,2,null,{}]
15:25:50 INFO - 1498775150015 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:25:50 INFO - 1498775150016 Marionette TRACE 372 <- [1,3,null,{}]
15:25:50 INFO - 1498775150017 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
15:25:50 INFO - 1498775150017 Marionette TRACE 372 <- [1,4,null,{}]
15:25:50 INFO - 1498775150019 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}]
15:25:50 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498775150021 Marionette TRACE 372 <- [1,5,null,{"value":null}]
15:25:50 INFO - 1498775150022 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
15:25:50 INFO - 1498775150023 Marionette TRACE 372 <- [1,6,null,{}]
15:25:50 INFO - 1498775150024 Marionette DEBUG Closed connection 372
15:25:50 INFO - 1498775150025 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49747
15:25:50 INFO - 1498775150025 Marionette DEBUG Closed connection 373
15:25:50 INFO - 1498775150026 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49748
15:25:50 INFO - 1498775150027 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:50 INFO - 1498775150028 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150030 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150032 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d0bfa923-fd15-4ccb-98e9-0057da783f92","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150034 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
15:25:50 INFO - 1498775150035 Marionette TRACE 374 <- [1,2,null,{}]
15:25:50 INFO - 1498775150036 Marionette DEBUG Closed connection 374
15:25:50 INFO - 1498775150036 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49749
15:25:50 INFO - 1498775150037 Marionette DEBUG Closed connection 375
15:25:50 INFO - 1498775150038 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49750
15:25:50 INFO - 1498775150039 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:25:50 INFO - 1498775150039 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150041 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150044 Marionette TRACE 376 <- [1,1,null,{"sessionId":"759f41d3-1a55-4420-92d5-702082f7adaf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150045 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"}]
15:25:50 INFO - 1498775150047 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"
15:25:50 INFO - 1498775150048 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"
15:25:50 INFO - 1498775150048 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"
15:25:50 INFO - 1498775150053 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"
15:25:50 INFO - 1498775150056 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"
15:25:50 INFO - 1498775150058 Marionette TRACE 376 <- [1,2,null,{}]
15:25:50 INFO - 1498775150064 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:25:50 INFO - 1498775150066 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40d1347b-7629-4220-8269-7ebf1b1909f9","ELEMENT":"40d1347b-7629-4220-8269-7ebf1b1909f9"}}]
15:25:50 INFO - 1498775150067 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"40d1347b-7629-4220-8269-7ebf1b1909f9"}]
15:25:50 INFO - 1498775150304 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:25:50 INFO - 1498775150305 Marionette TRACE 376 <- [1,4,null,{}]
15:25:50 INFO - 1498775150306 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"40d1347b-7629-4220-8269-7ebf1b1909f9","name":"selected"}]
15:25:50 INFO - 1498775150310 Marionette TRACE 376 <- [1,5,null,{"value":true}]
15:25:50 INFO - 1498775150312 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}]
15:25:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498775150314 Marionette TRACE 376 <- [1,6,null,{"value":null}]
15:25:50 INFO - 1498775150315 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
15:25:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 313ms
15:25:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:25:50 INFO - 1498775150316 Marionette TRACE 376 <- [1,7,null,{}]
15:25:50 INFO - 1498775150318 Marionette DEBUG Closed connection 376
15:25:50 INFO - 1498775150319 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49751
15:25:50 INFO - 1498775150319 Marionette DEBUG Closed connection 377
15:25:50 INFO - 1498775150320 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49752
15:25:50 INFO - 1498775150321 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:50 INFO - 1498775150321 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150323 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150326 Marionette TRACE 378 <- [1,1,null,{"sessionId":"55972683-5b0b-45f4-ab12-146859b439a5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150328 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
15:25:50 INFO - 1498775150328 Marionette TRACE 378 <- [1,2,null,{}]
15:25:50 INFO - 1498775150329 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:25:50 INFO - 1498775150329 Marionette TRACE 378 <- [1,3,null,{}]
15:25:50 INFO - 1498775150330 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
15:25:50 INFO - 1498775150330 Marionette TRACE 378 <- [1,4,null,{}]
15:25:50 INFO - 1498775150332 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}]
15:25:50 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498775150335 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:25:50 INFO - 1498775150336 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:25:50 INFO - 1498775150337 Marionette TRACE 378 <- [1,6,null,{}]
15:25:50 INFO - 1498775150338 Marionette DEBUG Closed connection 378
15:25:50 INFO - 1498775150339 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49753
15:25:50 INFO - 1498775150339 Marionette DEBUG Closed connection 379
15:25:50 INFO - 1498775150340 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49754
15:25:50 INFO - 1498775150341 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:50 INFO - 1498775150341 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150343 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150346 Marionette TRACE 380 <- [1,1,null,{"sessionId":"f09e268f-e9cd-44ea-a00a-69867535a416","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150347 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
15:25:50 INFO - 1498775150349 Marionette TRACE 380 <- [1,2,null,{}]
15:25:50 INFO - 1498775150350 Marionette DEBUG Closed connection 380
15:25:50 INFO - 1498775150350 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49755
15:25:50 INFO - 1498775150351 Marionette DEBUG Closed connection 381
15:25:50 INFO - 1498775150352 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49756
15:25:50 INFO - 1498775150352 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:25:50 INFO - 1498775150353 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150355 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150358 Marionette TRACE 382 <- [1,1,null,{"sessionId":"72924fc9-e3d3-416b-bc7b-d74ff0c763f1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150359 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"}]
15:25:50 INFO - 1498775150360 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"
15:25:50 INFO - 1498775150362 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"
15:25:50 INFO - 1498775150362 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"
15:25:50 INFO - 1498775150368 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"
15:25:50 INFO - 1498775150370 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"
15:25:50 INFO - 1498775150373 Marionette TRACE 382 <- [1,2,null,{}]
15:25:50 INFO - 1498775150375 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:25:50 INFO - 1498775150380 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"83a6af26-9e9b-493e-a59b-626fc27c3a65","ELEMENT":"83a6af26-9e9b-493e-a59b-626fc27c3a65"}}]
15:25:50 INFO - 1498775150381 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"83a6af26-9e9b-493e-a59b-626fc27c3a65"}]
15:25:50 INFO - 1498775150620 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:25:50 INFO - 1498775150621 Marionette TRACE 382 <- [1,4,null,{}]
15:25:50 INFO - 1498775150623 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}]
15:25:50 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498775150625 Marionette TRACE 382 <- [1,5,null,{"value":null}]
15:25:50 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 311ms
15:25:50 INFO - 1498775150626 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
15:25:50 INFO - 1498775150627 Marionette TRACE 382 <- [1,6,null,{}]
15:25:50 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:25:50 INFO - 1498775150629 Marionette DEBUG Closed connection 382
15:25:50 INFO - 1498775150630 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49757
15:25:50 INFO - 1498775150631 Marionette DEBUG Closed connection 383
15:25:50 INFO - 1498775150632 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49758
15:25:50 INFO - 1498775150633 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:50 INFO - 1498775150633 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150635 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150638 Marionette TRACE 384 <- [1,1,null,{"sessionId":"00db2508-4b8d-4e10-91be-0cd9082ed38d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150639 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
15:25:50 INFO - 1498775150639 Marionette TRACE 384 <- [1,2,null,{}]
15:25:50 INFO - 1498775150640 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:25:50 INFO - 1498775150640 Marionette TRACE 384 <- [1,3,null,{}]
15:25:50 INFO - 1498775150641 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
15:25:50 INFO - 1498775150641 Marionette TRACE 384 <- [1,4,null,{}]
15:25:50 INFO - 1498775150643 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}]
15:25:50 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498775150645 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:25:50 INFO - 1498775150646 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:25:50 INFO - 1498775150647 Marionette TRACE 384 <- [1,6,null,{}]
15:25:50 INFO - 1498775150648 Marionette DEBUG Closed connection 384
15:25:50 INFO - 1498775150649 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49759
15:25:50 INFO - 1498775150650 Marionette DEBUG Closed connection 385
15:25:50 INFO - 1498775150650 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49760
15:25:50 INFO - 1498775150651 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:50 INFO - 1498775150652 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150654 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150656 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c084b9c1-ab12-4a5e-a1a8-21d3bbc5b83a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150657 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
15:25:50 INFO - 1498775150658 Marionette TRACE 386 <- [1,2,null,{}]
15:25:50 INFO - 1498775150659 Marionette DEBUG Closed connection 386
15:25:50 INFO - 1498775150660 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49761
15:25:50 INFO - 1498775150661 Marionette DEBUG Closed connection 387
15:25:50 INFO - 1498775150661 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49762
15:25:50 INFO - 1498775150662 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:25:50 INFO - 1498775150663 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:50 INFO - 1498775150665 Marionette DEBUG Register listener.js for window 2147483649
15:25:50 INFO - 1498775150667 Marionette TRACE 388 <- [1,1,null,{"sessionId":"0a469a1b-623d-4f26-a0ba-6f46169a191d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:50 INFO - 1498775150668 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"}]
15:25:50 INFO - 1498775150670 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"
15:25:50 INFO - 1498775150671 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"
15:25:50 INFO - 1498775150671 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"
15:25:50 INFO - 1498775150678 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"
15:25:50 INFO - 1498775150680 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"
15:25:50 INFO - 1498775150684 Marionette TRACE 388 <- [1,2,null,{}]
15:25:50 INFO - 1498775150687 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:25:50 INFO - 1498775150689 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"45759fed-178e-4db9-bd7d-aa914a1f0ad0","ELEMENT":"45759fed-178e-4db9-bd7d-aa914a1f0ad0"}}]
15:25:50 INFO - 1498775150689 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"45759fed-178e-4db9-bd7d-aa914a1f0ad0"}]
15:25:50 INFO - 1498775150929 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:25:50 INFO - 1498775150930 Marionette TRACE 388 <- [1,4,null,{}]
15:25:50 INFO - 1498775150932 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}]
15:25:50 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498775150976 Marionette TRACE 388 <- [1,5,null,{"value":null}]
15:25:50 INFO - 1498775150981 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
15:25:50 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 356ms
15:25:50 INFO - TEST-START | test_click.py TestClick.test_input_file
15:25:50 INFO - 1498775150984 Marionette TRACE 388 <- [1,6,null,{}]
15:25:50 INFO - 1498775150987 Marionette DEBUG Closed connection 388
15:25:50 INFO - 1498775150988 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49763
15:25:50 INFO - 1498775150989 Marionette DEBUG Closed connection 389
15:25:50 INFO - 1498775150990 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49764
15:25:50 INFO - 1498775150991 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:50 INFO - 1498775150994 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775150996 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151004 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c74601fc-7722-4ec5-9e61-bbb598bccc33","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151006 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
15:25:51 INFO - 1498775151008 Marionette TRACE 390 <- [1,2,null,{}]
15:25:51 INFO - 1498775151009 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:25:51 INFO - 1498775151010 Marionette TRACE 390 <- [1,3,null,{}]
15:25:51 INFO - 1498775151011 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
15:25:51 INFO - 1498775151013 Marionette TRACE 390 <- [1,4,null,{}]
15:25:51 INFO - 1498775151014 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}]
15:25:51 INFO - TEST-START: test_click.py TestClick.test_input_file1498775151020 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:25:51 INFO - 1498775151029 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:25:51 INFO - 1498775151031 Marionette TRACE 390 <- [1,6,null,{}]
15:25:51 INFO - 1498775151033 Marionette DEBUG Closed connection 390
15:25:51 INFO - 1498775151034 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49765
15:25:51 INFO - 1498775151035 Marionette DEBUG Closed connection 391
15:25:51 INFO - 1498775151036 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49766
15:25:51 INFO - 1498775151037 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:51 INFO - 1498775151038 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775151040 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151045 Marionette TRACE 392 <- [1,1,null,{"sessionId":"74041cb5-f015-4120-aacb-b97a1de2cf2d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151046 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
15:25:51 INFO - 1498775151048 Marionette TRACE 392 <- [1,2,null,{}]
15:25:51 INFO - 1498775151049 Marionette DEBUG Closed connection 392
15:25:51 INFO - 1498775151050 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49767
15:25:51 INFO - 1498775151051 Marionette DEBUG Closed connection 393
15:25:51 INFO - 1498775151052 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49768
15:25:51 INFO - 1498775151053 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:25:51 INFO - 1498775151053 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775151056 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151059 Marionette TRACE 394 <- [1,1,null,{"sessionId":"7cc57149-0ff2-4d7e-aa32-b771f6764ddb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151062 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:25:51 INFO - 1498775151064 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"
15:25:51 INFO - 1498775151065 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"
15:25:51 INFO - 1498775151066 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"
15:25:51 INFO - 1498775151071 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:25:51 INFO - 1498775151075 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:25:51 INFO - 1498775151084 Marionette TRACE 394 <- [1,2,null,{}]
15:25:51 INFO - 1498775151087 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:25:51 INFO - 1498775151090 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"92267191-1975-4c37-8e7e-aceceeb44b04","ELEMENT":"92267191-1975-4c37-8e7e-aceceeb44b04"}}]
15:25:51 INFO - 1498775151090 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"92267191-1975-4c37-8e7e-aceceeb44b04"}]
15:25:51 INFO - 1498775151121 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nInvalidArgumentError@chrome://marionette/content/error.js:325:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:158: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
15:25:51 INFO - 1498775151123 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}]
15:25:51 INFO - TEST-END: test_click.py TestClick.test_input_file1498775151126 Marionette TRACE 394 <- [1,5,null,{"value":null}]
15:25:51 INFO - 1498775151126 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
15:25:51 INFO - 1498775151128 Marionette TRACE 394 <- [1,6,null,{}]
15:25:51 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 143ms
15:25:51 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:25:51 INFO - 1498775151129 Marionette DEBUG Closed connection 394
15:25:51 INFO - 1498775151130 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49769
15:25:51 INFO - 1498775151131 Marionette DEBUG Closed connection 395
15:25:51 INFO - 1498775151132 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49770
15:25:51 INFO - 1498775151132 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:51 INFO - 1498775151133 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775151135 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151139 Marionette TRACE 396 <- [1,1,null,{"sessionId":"7822cade-e9a5-425b-bd14-30646c40b748","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151140 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
15:25:51 INFO - 1498775151140 Marionette TRACE 396 <- [1,2,null,{}]
15:25:51 INFO - 1498775151141 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:25:51 INFO - 1498775151142 Marionette TRACE 396 <- [1,3,null,{}]
15:25:51 INFO - 1498775151143 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
15:25:51 INFO - 1498775151143 Marionette TRACE 396 <- [1,4,null,{}]
15:25:51 INFO - 1498775151145 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}]
15:25:51 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498775151147 Marionette TRACE 396 <- [1,5,null,{"value":null}]
15:25:51 INFO - 1498775151148 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
15:25:51 INFO - 1498775151149 Marionette TRACE 396 <- [1,6,null,{}]
15:25:51 INFO - 1498775151150 Marionette DEBUG Closed connection 396
15:25:51 INFO - 1498775151151 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49771
15:25:51 INFO - 1498775151152 Marionette DEBUG Closed connection 397
15:25:51 INFO - 1498775151152 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49772
15:25:51 INFO - 1498775151153 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:51 INFO - 1498775151154 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775151156 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151160 Marionette TRACE 398 <- [1,1,null,{"sessionId":"af51507c-602d-487b-902d-744f5eb6d7e7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151162 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
15:25:51 INFO - 1498775151163 Marionette TRACE 398 <- [1,2,null,{}]
15:25:51 INFO - 1498775151165 Marionette DEBUG Closed connection 398
15:25:51 INFO - 1498775151167 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49773
15:25:51 INFO - 1498775151168 Marionette DEBUG Closed connection 399
15:25:51 INFO - 1498775151169 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49774
15:25:51 INFO - 1498775151170 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:25:51 INFO - 1498775151171 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775151174 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151177 Marionette TRACE 400 <- [1,1,null,{"sessionId":"43939403-9873-41e5-9fb2-fcf235d2c9db","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151178 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"}]
15:25:51 INFO - 1498775151179 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:25:51 INFO - 1498775151181 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:25:51 INFO - 1498775151181 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:25:51 INFO - 1498775151187 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"
15:25:51 INFO - 1498775151190 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"
15:25:51 INFO - 1498775151199 Marionette TRACE 400 <- [1,2,null,{}]
15:25:51 INFO - 1498775151203 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:25:51 INFO - 1498775151205 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a9f762cb-b4e7-4d4b-a30c-29c812138f9c","ELEMENT":"a9f762cb-b4e7-4d4b-a30c-29c812138f9c"}}]
15:25:51 INFO - 1498775151206 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:25:51 INFO - 1498775151208 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7a25fba-9b0f-45d0-b25c-3390e285ac2d","ELEMENT":"c7a25fba-9b0f-45d0-b25c-3390e285ac2d"}}]
15:25:51 INFO - 1498775151209 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a9f762cb-b4e7-4d4b-a30c-29c812138f9c"}]
15:25:51 INFO - 1498775151444 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:25:51 INFO - 1498775151446 Marionette TRACE 400 <- [1,5,null,{}]
15:25:51 INFO - 1498775151447 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"c7a25fba-9b0f-45d0-b25c-3390e285ac2d"}]
15:25:51 INFO - 1498775151464 Marionette TRACE 400 <- [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:222:5\nElementClickInterceptedError@chrome://marionette/content/error.js:304:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:185: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
15:25:51 INFO - 1498775151466 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:25:51 INFO - 1498775151469 Marionette TRACE 400 <- [1,7,null,{"value":false}]
15:25:51 INFO - 1498775151470 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}]
15:25:51 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 347ms
15:25:51 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498775151473 Marionette TRACE 400 <- [1,8,null,{"value":null}]
15:25:51 INFO - 1498775151474 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
15:25:51 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:25:51 INFO - 1498775151475 Marionette TRACE 400 <- [1,9,null,{}]
15:25:51 INFO - 1498775151477 Marionette DEBUG Closed connection 400
15:25:51 INFO - 1498775151479 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49775
15:25:51 INFO - 1498775151480 Marionette DEBUG Closed connection 401
15:25:51 INFO - 1498775151480 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49776
15:25:51 INFO - 1498775151481 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:51 INFO - 1498775151482 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775151484 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151489 Marionette TRACE 402 <- [1,1,null,{"sessionId":"143c3f3e-fbd0-4426-83a9-a7205fbc991d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151490 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
15:25:51 INFO - 1498775151490 Marionette TRACE 402 <- [1,2,null,{}]
15:25:51 INFO - 1498775151491 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:25:51 INFO - 1498775151492 Marionette TRACE 402 <- [1,3,null,{}]
15:25:51 INFO - 1498775151492 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
15:25:51 INFO - 1498775151493 Marionette TRACE 402 <- [1,4,null,{}]
15:25:51 INFO - 1498775151495 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}]
15:25:51 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498775151498 Marionette TRACE 402 <- [1,5,null,{"value":null}]
15:25:51 INFO - 1498775151498 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
15:25:51 INFO - 1498775151500 Marionette TRACE 402 <- [1,6,null,{}]
15:25:51 INFO - 1498775151501 Marionette DEBUG Closed connection 402
15:25:51 INFO - 1498775151502 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49777
15:25:51 INFO - 1498775151502 Marionette DEBUG Closed connection 403
15:25:51 INFO - 1498775151503 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49778
15:25:51 INFO - 1498775151504 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:25:51 INFO - 1498775151504 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775151507 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151510 Marionette TRACE 404 <- [1,1,null,{"sessionId":"355090f0-4e80-45a5-920f-5cbeec2565c1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151511 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
15:25:51 INFO - 1498775151513 Marionette TRACE 404 <- [1,2,null,{}]
15:25:51 INFO - 1498775151514 Marionette DEBUG Closed connection 404
15:25:51 INFO - 1498775151514 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49779
15:25:51 INFO - 1498775151515 Marionette DEBUG Closed connection 405
15:25:51 INFO - 1498775151516 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49780
15:25:51 INFO - 1498775151517 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:25:51 INFO - 1498775151517 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}
15:25:51 INFO - 1498775151519 Marionette DEBUG Register listener.js for window 2147483649
15:25:51 INFO - 1498775151522 Marionette TRACE 406 <- [1,1,null,{"sessionId":"c8db9532-8b33-4acc-9180-097e14af5008","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2700,"moz:profile":"c:\\users\\cltbld~1.t-w\\appdata\\local\\temp\\tmpnfvlpi.mozrunner","moz:accessibilityChecks":false}}]
15:25:51 INFO - 1498775151524 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"}]
15:25:51 INFO - 1498775151525 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"
15:25:51 INFO - 1498775151528 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"
15:25:51 INFO - 1498775151528 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"
15:25:51 INFO - 1498775151535 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"
15:25:51 INFO - 1498775151538 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"
15:25:51 INFO - 1498775151541 Marionette TRACE 406 <- [1,2,null,{}]
15:25:51 INFO - 1498775151547 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:25:51 INFO - 1498775151551 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b20f8ec7-c7ab-4a75-99b9-5be945a4170a","ELEMENT":"b20f8ec7-c7ab-4a75-99b9-5be945a4170a"}}]
15:25:51 INFO - 1498775151551 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b20f8ec7-c7ab-4a75-99b9-5be945a4170a"}]
15:25:51 INFO - 1498775151575 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
15:25:51 INFO - 1498775151576 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"b20f8ec7-c7ab-4a75-99b9-5be945a4170a"}]
15:25:51 INFO - 1498775151600 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element