could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:49:22 INFO - 1500659362397 Marionette TRACE 353 -> [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:49:22 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500659362400 Marionette TRACE 353 <- [1,5,null,{"value":null}]
10:49:22 INFO - 1500659362401 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
10:49:22 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 155ms
10:49:22 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:49:22 INFO - 1500659362403 Marionette TRACE 353 <- [1,6,null,{}]
10:49:22 INFO - 1500659362405 Marionette DEBUG Closed connection 353
10:49:22 INFO - 1500659362406 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49781
10:49:22 INFO - 1500659362407 Marionette DEBUG Closed connection 354
10:49:22 INFO - 1500659362408 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49782
10:49:22 INFO - 1500659362409 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:22 INFO - 1500659362409 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:22 INFO - 1500659362412 Marionette DEBUG Register listener.js for window 15
10:49:22 INFO - 1500659362423 Marionette TRACE 355 <- [1,1,null,{"sessionId":"6ec487d0-7f24-4080-aaee-b513346654b1","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:22 INFO - 1500659362425 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
10:49:22 INFO - 1500659362426 Marionette TRACE 355 <- [1,2,null,{}]
10:49:22 INFO - 1500659362427 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:22 INFO - 1500659362427 Marionette TRACE 355 <- [1,3,null,{}]
10:49:22 INFO - 1500659362428 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:22 INFO - 1500659362429 Marionette TRACE 355 <- [1,4,null,{}]
10:49:22 INFO - 1500659362431 Marionette TRACE 355 -> [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:49:22 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500659362434 Marionette TRACE 355 <- [1,5,null,{"value":null}]
10:49:22 INFO - 1500659362435 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
10:49:22 INFO - 1500659362437 Marionette TRACE 355 <- [1,6,null,{}]
10:49:22 INFO - 1500659362439 Marionette DEBUG Closed connection 355
10:49:22 INFO - 1500659362440 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49783
10:49:22 INFO - 1500659362441 Marionette DEBUG Closed connection 356
10:49:22 INFO - 1500659362441 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49784
10:49:22 INFO - 1500659362443 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:22 INFO - 1500659362443 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:22 INFO - 1500659362446 Marionette DEBUG Register listener.js for window 15
10:49:22 INFO - 1500659362449 Marionette TRACE 357 <- [1,1,null,{"sessionId":"6003e832-37e1-42cd-be03-6afa345ca769","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:22 INFO - 1500659362451 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
10:49:22 INFO - 1500659362453 Marionette TRACE 357 <- [1,2,null,{}]
10:49:22 INFO - 1500659362455 Marionette DEBUG Closed connection 357
10:49:22 INFO - 1500659362456 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49785
10:49:22 INFO - 1500659362457 Marionette DEBUG Closed connection 358
10:49:22 INFO - 1500659362458 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49786
10:49:22 INFO - 1500659362459 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:22 INFO - 1500659362459 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:22 INFO - 1500659362462 Marionette DEBUG Register listener.js for window 15
10:49:22 INFO - 1500659362465 Marionette TRACE 359 <- [1,1,null,{"sessionId":"6dac48a6-294c-4637-b7c4-50334f2dbd49","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:22 INFO - 1500659362468 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49346/clicks.html"}]
10:49:22 INFO - 1500659362469 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/hidden.html"
10:49:22 INFO - 1500659362475 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/hidden.html"
10:49:22 INFO - 1500659362476 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/hidden.html"
10:49:22 INFO - 1500659362502 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49346/clicks.html"
10:49:22 INFO - 1500659362505 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49346/clicks.html"
10:49:22 INFO - 1500659362509 Marionette TRACE 359 <- [1,2,null,{}]
10:49:22 INFO - 1500659362518 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:49:22 INFO - 1500659362521 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c94f4f5-9af6-4416-b510-5da462454af7","ELEMENT":"3c94f4f5-9af6-4416-b510-5da462454af7"}}]
10:49:22 INFO - 1500659362522 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"3c94f4f5-9af6-4416-b510-5da462454af7"}]
10:49:22 INFO - 1500659362566 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/clicks.html"
10:49:22 INFO - 1500659362575 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/clicks.html"
10:49:22 INFO - 1500659362575 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/clicks.html"
10:49:22 INFO - 1500659362608 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49346/test.html"
10:49:22 INFO - 1500659362618 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49346/test.html"
10:49:22 INFO - 1500659362622 Marionette TRACE 359 <- [1,4,null,{}]
10:49:22 INFO - 1500659362624 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:49:22 INFO - 1500659362634 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"893d6f28-760a-4fbb-a934-71ea283c8290","ELEMENT":"893d6f28-760a-4fbb-a934-71ea283c8290"}}]
10:49:22 INFO - 1500659362636 Marionette TRACE 359 -> [0,6,"getTitle",{}]
10:49:22 INFO - 1500659362636 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
10:49:22 INFO - 1500659362638 Marionette TRACE 359 -> [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:49:22 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500659362641 Marionette TRACE 359 <- [1,7,null,{"value":null}]
10:49:22 INFO - 1500659362642 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
10:49:22 INFO - 1500659362644 Marionette TRACE 359 <- [1,8,null,{}]
10:49:22 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 243ms
10:49:22 INFO - TEST-START | test_click.py TestClick.test_container_element
10:49:22 INFO - 1500659362648 Marionette DEBUG Closed connection 359
10:49:22 INFO - 1500659362649 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49789
10:49:22 INFO - 1500659362650 Marionette DEBUG Closed connection 360
10:49:22 INFO - 1500659362651 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49790
10:49:22 INFO - 1500659362652 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:22 INFO - 1500659362652 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:22 INFO - 1500659362655 Marionette DEBUG Register listener.js for window 15
10:49:22 INFO - 1500659362659 Marionette TRACE 361 <- [1,1,null,{"sessionId":"a49bb142-da91-47e6-bb1d-3067ac6aae08","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:22 INFO - 1500659362664 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
10:49:22 INFO - 1500659362665 Marionette TRACE 361 <- [1,2,null,{}]
10:49:22 INFO - 1500659362666 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:22 INFO - 1500659362666 Marionette TRACE 361 <- [1,3,null,{}]
10:49:22 INFO - 1500659362667 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:22 INFO - 1500659362668 Marionette TRACE 361 <- [1,4,null,{}]
10:49:22 INFO - 1500659362670 Marionette TRACE 361 -> [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:49:22 INFO - TEST-START: test_click.py TestClick.test_container_element1500659362673 Marionette TRACE 361 <- [1,5,null,{"value":null}]
10:49:22 INFO - 1500659362674 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
10:49:22 INFO - 1500659362675 Marionette TRACE 361 <- [1,6,null,{}]
10:49:22 INFO - 1500659362678 Marionette DEBUG Closed connection 361
10:49:22 INFO - 1500659362679 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49791
10:49:22 INFO - 1500659362679 Marionette DEBUG Closed connection 362
10:49:22 INFO - 1500659362680 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49792
10:49:22 INFO - 1500659362681 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:22 INFO - 1500659362682 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:22 INFO - 1500659362685 Marionette DEBUG Register listener.js for window 15
10:49:22 INFO - 1500659362688 Marionette TRACE 363 <- [1,1,null,{"sessionId":"579f2af4-afb2-43f4-a955-9ee64a280f4d","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:22 INFO - 1500659362691 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
10:49:22 INFO - 1500659362692 Marionette TRACE 363 <- [1,2,null,{}]
10:49:22 INFO - 1500659362696 Marionette DEBUG Closed connection 363
10:49:22 INFO - 1500659362697 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49793
10:49:22 INFO - 1500659362698 Marionette DEBUG Closed connection 364
10:49:22 INFO - 1500659362699 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49794
10:49:22 INFO - 1500659362700 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:22 INFO - 1500659362700 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:22 INFO - 1500659362703 Marionette DEBUG Register listener.js for window 15
10:49:22 INFO - 1500659362707 Marionette TRACE 365 <- [1,1,null,{"sessionId":"22c02d20-cea0-4016-b77c-92ccf124dee2","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:22 INFO - 1500659362710 Marionette TRACE 365 -> [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:49:22 INFO - 1500659362712 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/test.html"
10:49:22 INFO - 1500659362714 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/test.html"
10:49:22 INFO - 1500659362715 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/test.html"
10:49:22 INFO - 1500659362741 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:49:22 INFO - 1500659362747 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:49:22 INFO - 1500659362751 Marionette TRACE 365 <- [1,2,null,{}]
10:49:22 INFO - 1500659362753 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:49:22 INFO - 1500659362756 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6b2391bf-fdff-4b7d-8e80-37b13b9c5f47","ELEMENT":"6b2391bf-fdff-4b7d-8e80-37b13b9c5f47"}}]
10:49:22 INFO - 1500659362757 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"6b2391bf-fdff-4b7d-8e80-37b13b9c5f47"}]
10:49:23 INFO - 1500659363009 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:23 INFO - 1500659363010 Marionette TRACE 365 <- [1,4,null,{}]
10:49:23 INFO - 1500659363011 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"6b2391bf-fdff-4b7d-8e80-37b13b9c5f47","name":"selected"}]
10:49:23 INFO - 1500659363016 Marionette TRACE 365 <- [1,5,null,{"value":true}]
10:49:23 INFO - 1500659363018 Marionette TRACE 365 -> [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:49:23 INFO - TEST-END: test_click.py TestClick.test_container_element1500659363021 Marionette TRACE 365 <- [1,6,null,{"value":null}]
10:49:23 INFO - 1500659363022 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
10:49:23 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 378ms
10:49:23 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:49:23 INFO - 1500659363024 Marionette TRACE 365 <- [1,7,null,{}]
10:49:23 INFO - 1500659363026 Marionette DEBUG Closed connection 365
10:49:23 INFO - 1500659363027 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49795
10:49:23 INFO - 1500659363028 Marionette DEBUG Closed connection 366
10:49:23 INFO - 1500659363029 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49796
10:49:23 INFO - 1500659363030 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:23 INFO - 1500659363030 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363033 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363044 Marionette TRACE 367 <- [1,1,null,{"sessionId":"54592834-e5df-417f-9c3d-8f10469b87eb","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363046 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
10:49:23 INFO - 1500659363047 Marionette TRACE 367 <- [1,2,null,{}]
10:49:23 INFO - 1500659363048 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:23 INFO - 1500659363048 Marionette TRACE 367 <- [1,3,null,{}]
10:49:23 INFO - 1500659363049 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:23 INFO - 1500659363050 Marionette TRACE 367 <- [1,4,null,{}]
10:49:23 INFO - 1500659363052 Marionette TRACE 367 -> [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:49:23 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500659363055 Marionette TRACE 367 <- [1,5,null,{"value":null}]
10:49:23 INFO - 1500659363056 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
10:49:23 INFO - 1500659363058 Marionette TRACE 367 <- [1,6,null,{}]
10:49:23 INFO - 1500659363060 Marionette DEBUG Closed connection 367
10:49:23 INFO - 1500659363061 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49797
10:49:23 INFO - 1500659363061 Marionette DEBUG Closed connection 368
10:49:23 INFO - 1500659363062 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49798
10:49:23 INFO - 1500659363063 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:23 INFO - 1500659363064 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363067 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363080 Marionette TRACE 369 <- [1,1,null,{"sessionId":"60b913b3-7983-479d-82c6-0b5a97174fe2","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363083 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
10:49:23 INFO - 1500659363085 Marionette TRACE 369 <- [1,2,null,{}]
10:49:23 INFO - 1500659363087 Marionette DEBUG Closed connection 369
10:49:23 INFO - 1500659363088 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49799
10:49:23 INFO - 1500659363088 Marionette DEBUG Closed connection 370
10:49:23 INFO - 1500659363089 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49800
10:49:23 INFO - 1500659363090 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:23 INFO - 1500659363091 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363094 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363097 Marionette TRACE 371 <- [1,1,null,{"sessionId":"72a3870d-6346-42fc-bf12-24f7b46bee6a","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363099 Marionette TRACE 371 -> [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:49:23 INFO - 1500659363102 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:49:23 INFO - 1500659363104 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:49:23 INFO - 1500659363104 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:49:23 INFO - 1500659363128 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:49:23 INFO - 1500659363130 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:49:23 INFO - 1500659363134 Marionette TRACE 371 <- [1,2,null,{}]
10:49:23 INFO - 1500659363135 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:49:23 INFO - 1500659363138 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3d3117cc-7a38-4083-893f-1820987e2615","ELEMENT":"3d3117cc-7a38-4083-893f-1820987e2615"}}]
10:49:23 INFO - 1500659363139 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"3d3117cc-7a38-4083-893f-1820987e2615"}]
10:49:23 INFO - 1500659363392 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:23 INFO - 1500659363392 Marionette TRACE 371 <- [1,4,null,{}]
10:49:23 INFO - 1500659363394 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"3d3117cc-7a38-4083-893f-1820987e2615","name":"selected"}]
10:49:23 INFO - 1500659363399 Marionette TRACE 371 <- [1,5,null,{"value":true}]
10:49:23 INFO - 1500659363400 Marionette TRACE 371 -> [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:49:23 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500659363403 Marionette TRACE 371 <- [1,6,null,{"value":null}]
10:49:23 INFO - 1500659363404 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
10:49:23 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 382ms
10:49:23 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:49:23 INFO - 1500659363406 Marionette TRACE 371 <- [1,7,null,{}]
10:49:23 INFO - 1500659363408 Marionette DEBUG Closed connection 371
10:49:23 INFO - 1500659363409 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49801
10:49:23 INFO - 1500659363410 Marionette DEBUG Closed connection 372
10:49:23 INFO - 1500659363411 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49802
10:49:23 INFO - 1500659363412 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:23 INFO - 1500659363413 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363415 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363419 Marionette TRACE 373 <- [1,1,null,{"sessionId":"5e5950d8-2c5b-4c3f-938f-48ccb347ba48","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363421 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
10:49:23 INFO - 1500659363422 Marionette TRACE 373 <- [1,2,null,{}]
10:49:23 INFO - 1500659363423 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:23 INFO - 1500659363423 Marionette TRACE 373 <- [1,3,null,{}]
10:49:23 INFO - 1500659363424 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:23 INFO - 1500659363425 Marionette TRACE 373 <- [1,4,null,{}]
10:49:23 INFO - 1500659363427 Marionette TRACE 373 -> [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:49:23 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500659363429 Marionette TRACE 373 <- [1,5,null,{"value":null}]
10:49:23 INFO - 1500659363430 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
10:49:23 INFO - 1500659363432 Marionette TRACE 373 <- [1,6,null,{}]
10:49:23 INFO - 1500659363434 Marionette DEBUG Closed connection 373
10:49:23 INFO - 1500659363435 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49803
10:49:23 INFO - 1500659363436 Marionette DEBUG Closed connection 374
10:49:23 INFO - 1500659363437 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49804
10:49:23 INFO - 1500659363438 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:23 INFO - 1500659363438 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363441 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363445 Marionette TRACE 375 <- [1,1,null,{"sessionId":"4a071b4e-9087-4d80-a16b-8e124529709d","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363447 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
10:49:23 INFO - 1500659363448 Marionette TRACE 375 <- [1,2,null,{}]
10:49:23 INFO - 1500659363450 Marionette DEBUG Closed connection 375
10:49:23 INFO - 1500659363451 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49805
10:49:23 INFO - 1500659363452 Marionette DEBUG Closed connection 376
10:49:23 INFO - 1500659363453 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49806
10:49:23 INFO - 1500659363454 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:23 INFO - 1500659363455 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363457 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363461 Marionette TRACE 377 <- [1,1,null,{"sessionId":"4bc2834b-e2bd-428d-a633-0293323b4a86","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363463 Marionette TRACE 377 -> [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:49:23 INFO - 1500659363465 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:49:23 INFO - 1500659363467 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:49:23 INFO - 1500659363467 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:49:23 INFO - 1500659363489 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:49:23 INFO - 1500659363492 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:49:23 INFO - 1500659363503 Marionette TRACE 377 <- [1,2,null,{}]
10:49:23 INFO - 1500659363505 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:49:23 INFO - 1500659363508 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"202944cc-e4d3-4f94-8d15-ab49f311cc23","ELEMENT":"202944cc-e4d3-4f94-8d15-ab49f311cc23"}}]
10:49:23 INFO - 1500659363510 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"202944cc-e4d3-4f94-8d15-ab49f311cc23"}]
10:49:23 INFO - 1500659363752 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:23 INFO - 1500659363753 Marionette TRACE 377 <- [1,4,null,{}]
10:49:23 INFO - 1500659363755 Marionette TRACE 377 -> [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:49:23 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500659363758 Marionette TRACE 377 <- [1,5,null,{"value":null}]
10:49:23 INFO - 1500659363759 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
10:49:23 INFO - 1500659363760 Marionette TRACE 377 <- [1,6,null,{}]
10:49:23 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 354ms
10:49:23 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:49:23 INFO - 1500659363763 Marionette DEBUG Closed connection 377
10:49:23 INFO - 1500659363764 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49807
10:49:23 INFO - 1500659363765 Marionette DEBUG Closed connection 378
10:49:23 INFO - 1500659363765 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49808
10:49:23 INFO - 1500659363767 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:23 INFO - 1500659363767 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363770 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363774 Marionette TRACE 379 <- [1,1,null,{"sessionId":"6be7598e-482e-4840-b227-0c85a96a97ed","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363776 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
10:49:23 INFO - 1500659363776 Marionette TRACE 379 <- [1,2,null,{}]
10:49:23 INFO - 1500659363777 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:23 INFO - 1500659363778 Marionette TRACE 379 <- [1,3,null,{}]
10:49:23 INFO - 1500659363779 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:23 INFO - 1500659363779 Marionette TRACE 379 <- [1,4,null,{}]
10:49:23 INFO - 1500659363781 Marionette TRACE 379 -> [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:49:23 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500659363784 Marionette TRACE 379 <- [1,5,null,{"value":null}]
10:49:23 INFO - 1500659363785 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
10:49:23 INFO - 1500659363786 Marionette TRACE 379 <- [1,6,null,{}]
10:49:23 INFO - 1500659363788 Marionette DEBUG Closed connection 379
10:49:23 INFO - 1500659363789 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49809
10:49:23 INFO - 1500659363790 Marionette DEBUG Closed connection 380
10:49:23 INFO - 1500659363791 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49810
10:49:23 INFO - 1500659363792 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:23 INFO - 1500659363800 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363803 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363806 Marionette TRACE 381 <- [1,1,null,{"sessionId":"e87b6211-1ef5-4d53-9a96-b09615e82468","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363809 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
10:49:23 INFO - 1500659363810 Marionette TRACE 381 <- [1,2,null,{}]
10:49:23 INFO - 1500659363812 Marionette DEBUG Closed connection 381
10:49:23 INFO - 1500659363813 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49811
10:49:23 INFO - 1500659363814 Marionette DEBUG Closed connection 382
10:49:23 INFO - 1500659363815 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49812
10:49:23 INFO - 1500659363816 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:23 INFO - 1500659363817 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:23 INFO - 1500659363820 Marionette DEBUG Register listener.js for window 15
10:49:23 INFO - 1500659363823 Marionette TRACE 383 <- [1,1,null,{"sessionId":"d45b6412-d814-469b-9cca-df045a8d553b","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:23 INFO - 1500659363826 Marionette TRACE 383 -> [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:49:23 INFO - 1500659363827 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:49:23 INFO - 1500659363829 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:49:23 INFO - 1500659363830 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:49:23 INFO - 1500659363852 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:49:23 INFO - 1500659363854 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:49:23 INFO - 1500659363858 Marionette TRACE 383 <- [1,2,null,{}]
10:49:23 INFO - 1500659363867 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:49:23 INFO - 1500659363870 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"27d7cdb7-f3fc-42f2-8cd2-0a18fce64ef9","ELEMENT":"27d7cdb7-f3fc-42f2-8cd2-0a18fce64ef9"}}]
10:49:23 INFO - 1500659363871 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"27d7cdb7-f3fc-42f2-8cd2-0a18fce64ef9"}]
10:49:24 INFO - 1500659364116 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:24 INFO - 1500659364117 Marionette TRACE 383 <- [1,4,null,{}]
10:49:24 INFO - 1500659364123 Marionette TRACE 383 -> [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:49:24 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500659364126 Marionette TRACE 383 <- [1,5,null,{"value":null}]
10:49:24 INFO - 1500659364128 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
10:49:24 INFO - 1500659364130 Marionette TRACE 383 <- [1,6,null,{}]
10:49:24 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 369ms
10:49:24 INFO - TEST-START | test_click.py TestClick.test_input_file
10:49:24 INFO - 1500659364132 Marionette DEBUG Closed connection 383
10:49:24 INFO - 1500659364133 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49813
10:49:24 INFO - 1500659364154 Marionette DEBUG Closed connection 384
10:49:24 INFO - 1500659364155 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49814
10:49:24 INFO - 1500659364156 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:24 INFO - 1500659364157 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364160 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364163 Marionette TRACE 385 <- [1,1,null,{"sessionId":"88ed3e8a-ad5d-444a-b7c7-2af1153d6c74","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364168 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
10:49:24 INFO - 1500659364169 Marionette TRACE 385 <- [1,2,null,{}]
10:49:24 INFO - 1500659364170 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:24 INFO - 1500659364170 Marionette TRACE 385 <- [1,3,null,{}]
10:49:24 INFO - 1500659364195 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:24 INFO - 1500659364196 Marionette TRACE 385 <- [1,4,null,{}]
10:49:24 INFO - 1500659364199 Marionette TRACE 385 -> [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:49:24 INFO - TEST-START: test_click.py TestClick.test_input_file1500659364202 Marionette TRACE 385 <- [1,5,null,{"value":null}]
10:49:24 INFO - 1500659364203 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
10:49:24 INFO - 1500659364204 Marionette TRACE 385 <- [1,6,null,{}]
10:49:24 INFO - 1500659364213 Marionette DEBUG Closed connection 385
10:49:24 INFO - 1500659364213 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49815
10:49:24 INFO - 1500659364215 Marionette DEBUG Closed connection 386
10:49:24 INFO - 1500659364215 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49816
10:49:24 INFO - 1500659364216 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:24 INFO - 1500659364217 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364220 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364223 Marionette TRACE 387 <- [1,1,null,{"sessionId":"9a9aed16-58ff-418e-b878-5e7f464d0fb5","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364226 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
10:49:24 INFO - 1500659364227 Marionette TRACE 387 <- [1,2,null,{}]
10:49:24 INFO - 1500659364229 Marionette DEBUG Closed connection 387
10:49:24 INFO - 1500659364230 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49817
10:49:24 INFO - 1500659364231 Marionette DEBUG Closed connection 388
10:49:24 INFO - 1500659364232 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49818
10:49:24 INFO - 1500659364234 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:24 INFO - 1500659364235 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364238 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364241 Marionette TRACE 389 <- [1,1,null,{"sessionId":"dfc53399-458a-46f3-87ae-5f6c873a0a70","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364244 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:49:24 INFO - 1500659364245 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:49:24 INFO - 1500659364248 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:49:24 INFO - 1500659364249 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:49:24 INFO - 1500659364270 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:24 INFO - 1500659364325 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:24 INFO - 1500659364337 Marionette TRACE 389 <- [1,2,null,{}]
10:49:24 INFO - 1500659364339 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:49:24 INFO - 1500659364342 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2c4b00ef-e6b6-4081-847c-05a18ab2c2ec","ELEMENT":"2c4b00ef-e6b6-4081-847c-05a18ab2c2ec"}}]
10:49:24 INFO - 1500659364346 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"2c4b00ef-e6b6-4081-847c-05a18ab2c2ec"}]
10:49:24 INFO - 1500659364375 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:49:24 INFO - 1500659364377 Marionette TRACE 389 -> [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:49:24 INFO - TEST-END: test_click.py TestClick.test_input_file1500659364380 Marionette TRACE 389 <- [1,5,null,{"value":null}]
10:49:24 INFO - 1500659364382 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
10:49:24 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 254ms
10:49:24 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:49:24 INFO - 1500659364384 Marionette TRACE 389 <- [1,6,null,{}]
10:49:24 INFO - 1500659364386 Marionette DEBUG Closed connection 389
10:49:24 INFO - 1500659364387 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49819
10:49:24 INFO - 1500659364388 Marionette DEBUG Closed connection 390
10:49:24 INFO - 1500659364389 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49820
10:49:24 INFO - 1500659364390 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:24 INFO - 1500659364391 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364393 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364397 Marionette TRACE 391 <- [1,1,null,{"sessionId":"a02b5b4b-b51f-4fef-af2c-7078e92b45e1","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364399 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
10:49:24 INFO - 1500659364400 Marionette TRACE 391 <- [1,2,null,{}]
10:49:24 INFO - 1500659364401 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:24 INFO - 1500659364401 Marionette TRACE 391 <- [1,3,null,{}]
10:49:24 INFO - 1500659364402 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:24 INFO - 1500659364403 Marionette TRACE 391 <- [1,4,null,{}]
10:49:24 INFO - 1500659364405 Marionette TRACE 391 -> [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:49:24 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500659364407 Marionette TRACE 391 <- [1,5,null,{"value":null}]
10:49:24 INFO - 1500659364408 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
10:49:24 INFO - 1500659364410 Marionette TRACE 391 <- [1,6,null,{}]
10:49:24 INFO - 1500659364412 Marionette DEBUG Closed connection 391
10:49:24 INFO - 1500659364413 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49821
10:49:24 INFO - 1500659364414 Marionette DEBUG Closed connection 392
10:49:24 INFO - 1500659364415 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49822
10:49:24 INFO - 1500659364416 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:24 INFO - 1500659364416 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364419 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364429 Marionette TRACE 393 <- [1,1,null,{"sessionId":"5f297aeb-3289-4383-864e-9534cffc4ba0","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364433 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
10:49:24 INFO - 1500659364435 Marionette TRACE 393 <- [1,2,null,{}]
10:49:24 INFO - 1500659364437 Marionette DEBUG Closed connection 393
10:49:24 INFO - 1500659364438 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49823
10:49:24 INFO - 1500659364440 Marionette DEBUG Closed connection 394
10:49:24 INFO - 1500659364441 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49824
10:49:24 INFO - 1500659364442 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:24 INFO - 1500659364443 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364446 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364449 Marionette TRACE 395 <- [1,1,null,{"sessionId":"f0d6903c-632f-443e-9081-c77b82356828","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364452 Marionette TRACE 395 -> [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:49:24 INFO - 1500659364454 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:24 INFO - 1500659364456 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:24 INFO - 1500659364457 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:49:24 INFO - 1500659364482 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:49:24 INFO - 1500659364484 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:49:24 INFO - 1500659364488 Marionette TRACE 395 <- [1,2,null,{}]
10:49:24 INFO - 1500659364489 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:49:24 INFO - 1500659364499 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"56076d01-1968-47ad-80cb-9a2bd680cbcb","ELEMENT":"56076d01-1968-47ad-80cb-9a2bd680cbcb"}}]
10:49:24 INFO - 1500659364501 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:49:24 INFO - 1500659364504 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d4e8596-19bc-45d0-9076-92ce561cdb3d","ELEMENT":"8d4e8596-19bc-45d0-9076-92ce561cdb3d"}}]
10:49:24 INFO - 1500659364505 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"56076d01-1968-47ad-80cb-9a2bd680cbcb"}]
10:49:24 INFO - 1500659364747 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:49:24 INFO - 1500659364748 Marionette TRACE 395 <- [1,5,null,{}]
10:49:24 INFO - 1500659364749 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"8d4e8596-19bc-45d0-9076-92ce561cdb3d"}]
10:49:24 INFO - 1500659364767 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:49:24 INFO - 1500659364769 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
10:49:24 INFO - 1500659364772 Marionette TRACE 395 <- [1,7,null,{"value":false}]
10:49:24 INFO - 1500659364774 Marionette TRACE 395 -> [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:49:24 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500659364777 Marionette TRACE 395 <- [1,8,null,{"value":null}]
10:49:24 INFO - 1500659364778 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
10:49:24 INFO - 1500659364779 Marionette TRACE 395 <- [1,9,null,{}]
10:49:24 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 395ms
10:49:24 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:49:24 INFO - 1500659364782 Marionette DEBUG Closed connection 395
10:49:24 INFO - 1500659364783 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49825
10:49:24 INFO - 1500659364784 Marionette DEBUG Closed connection 396
10:49:24 INFO - 1500659364784 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49826
10:49:24 INFO - 1500659364785 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:24 INFO - 1500659364786 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364789 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364793 Marionette TRACE 397 <- [1,1,null,{"sessionId":"bbc341c9-7884-40a6-aad3-60b9c183b73f","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364795 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
10:49:24 INFO - 1500659364796 Marionette TRACE 397 <- [1,2,null,{}]
10:49:24 INFO - 1500659364797 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:49:24 INFO - 1500659364797 Marionette TRACE 397 <- [1,3,null,{}]
10:49:24 INFO - 1500659364798 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
10:49:24 INFO - 1500659364799 Marionette TRACE 397 <- [1,4,null,{}]
10:49:24 INFO - 1500659364800 Marionette TRACE 397 -> [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:49:24 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500659364803 Marionette TRACE 397 <- [1,5,null,{"value":null}]
10:49:24 INFO - 1500659364804 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
10:49:24 INFO - 1500659364806 Marionette TRACE 397 <- [1,6,null,{}]
10:49:24 INFO - 1500659364808 Marionette DEBUG Closed connection 397
10:49:24 INFO - 1500659364809 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49827
10:49:24 INFO - 1500659364810 Marionette DEBUG Closed connection 398
10:49:24 INFO - 1500659364810 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49828
10:49:24 INFO - 1500659364811 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:49:24 INFO - 1500659364812 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364815 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364818 Marionette TRACE 399 <- [1,1,null,{"sessionId":"74765e8a-3f80-4f94-ace0-edda0c249c3b","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364821 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
10:49:24 INFO - 1500659364822 Marionette TRACE 399 <- [1,2,null,{}]
10:49:24 INFO - 1500659364824 Marionette DEBUG Closed connection 399
10:49:24 INFO - 1500659364825 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49829
10:49:24 INFO - 1500659364826 Marionette DEBUG Closed connection 400
10:49:24 INFO - 1500659364827 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49830
10:49:24 INFO - 1500659364828 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:49:24 INFO - 1500659364829 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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}
10:49:24 INFO - 1500659364831 Marionette DEBUG Register listener.js for window 15
10:49:24 INFO - 1500659364837 Marionette TRACE 401 <- [1,1,null,{"sessionId":"32350685-fc47-436b-bc25-c9d9255bec39","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":532,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpcwguk3.mozrunner","moz:accessibilityChecks":false}}]
10:49:24 INFO - 1500659364839 Marionette TRACE 401 -> [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:49:24 INFO - 1500659364841 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:49:24 INFO - 1500659364844 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:49:24 INFO - 1500659364844 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:49:24 INFO - 1500659364867 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:49:24 INFO - 1500659364869 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:49:24 INFO - 1500659364873 Marionette TRACE 401 <- [1,2,null,{}]
10:49:24 INFO - 1500659364881 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:49:24 INFO - 1500659364884 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cb618ab6-b6af-4104-b96f-67d177c41d39","ELEMENT":"cb618ab6-b6af-4104-b96f-67d177c41d39"}}]
10:49:24 INFO - 1500659364888 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"cb618ab6-b6af-4104-b96f-67d177c41d39"}]
10:49:24 INFO - 1500659364911 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
10:49:24 INFO - 1500659364913 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"cb618ab6-b6af-4104-b96f-67d177c41d39"}]
10:49:24 INFO - 1500659364935 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element