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]
18:02:29 INFO - 1500512549460 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
18:02:29 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500512549463 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:02:29 INFO - 1500512549464 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:02:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 138ms
18:02:29 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:02:29 INFO - 1500512549466 Marionette TRACE 352 <- [1,6,null,{}]
18:02:29 INFO - 1500512549468 Marionette DEBUG Closed connection 352
18:02:29 INFO - 1500512549469 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49784
18:02:29 INFO - 1500512549470 Marionette DEBUG Closed connection 353
18:02:29 INFO - 1500512549471 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49785
18:02:29 INFO - 1500512549472 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:29 INFO - 1500512549472 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:29 INFO - 1500512549475 Marionette DEBUG Register listener.js for window 6442450945
18:02:29 INFO - 1500512549479 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1710c741-9e1b-4d52-891f-cd4d607421e2","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:29 INFO - 1500512549480 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:02:29 INFO - 1500512549481 Marionette TRACE 354 <- [1,2,null,{}]
18:02:29 INFO - 1500512549482 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:02:29 INFO - 1500512549483 Marionette TRACE 354 <- [1,3,null,{}]
18:02:29 INFO - 1500512549484 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:02:29 INFO - 1500512549484 Marionette TRACE 354 <- [1,4,null,{}]
18:02:29 INFO - 1500512549486 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
18:02:29 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500512549489 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:02:29 INFO - 1500512549490 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:02:29 INFO - 1500512549491 Marionette TRACE 354 <- [1,6,null,{}]
18:02:29 INFO - 1500512549493 Marionette DEBUG Closed connection 354
18:02:29 INFO - 1500512549494 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49786
18:02:29 INFO - 1500512549495 Marionette DEBUG Closed connection 355
18:02:29 INFO - 1500512549495 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49787
18:02:29 INFO - 1500512549497 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:29 INFO - 1500512549497 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:29 INFO - 1500512549500 Marionette DEBUG Register listener.js for window 6442450945
18:02:29 INFO - 1500512549503 Marionette TRACE 356 <- [1,1,null,{"sessionId":"951d709a-2b49-4a3f-94b1-44b8e71c9480","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:29 INFO - 1500512549505 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:02:29 INFO - 1500512549506 Marionette TRACE 356 <- [1,2,null,{}]
18:02:29 INFO - 1500512549508 Marionette DEBUG Closed connection 356
18:02:29 INFO - 1500512549509 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49788
18:02:29 INFO - 1500512549510 Marionette DEBUG Closed connection 357
18:02:29 INFO - 1500512549511 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49789
18:02:29 INFO - 1500512549512 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:02:29 INFO - 1500512549512 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:29 INFO - 1500512549515 Marionette DEBUG Register listener.js for window 6442450945
18:02:29 INFO - 1500512549519 Marionette TRACE 358 <- [1,1,null,{"sessionId":"e774fac4-cf09-430b-b9dc-6ccea2ce2e5d","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:29 INFO - 1500512549520 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49342/clicks.html"}]
18:02:29 INFO - 1500512549522 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49342/hidden.html"
18:02:29 INFO - 1500512549530 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49342/hidden.html"
18:02:29 INFO - 1500512549531 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49342/hidden.html"
18:02:29 INFO - 1500512549538 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49342/clicks.html"
18:02:29 INFO - 1500512549544 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49342/clicks.html"
18:02:29 INFO - 1500512549550 Marionette TRACE 358 <- [1,2,null,{}]
18:02:29 INFO - 1500512549555 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:02:29 INFO - 1500512549558 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c5431b4b-8ef2-438d-810a-a2fe6d8877b8","ELEMENT":"c5431b4b-8ef2-438d-810a-a2fe6d8877b8"}}]
18:02:29 INFO - 1500512549559 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"c5431b4b-8ef2-438d-810a-a2fe6d8877b8"}]
18:02:29 INFO - 1500512549605 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49342/clicks.html"
18:02:29 INFO - 1500512549614 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49342/clicks.html"
18:02:29 INFO - 1500512549615 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49342/clicks.html"
18:02:29 INFO - 1500512549623 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49342/test.html"
18:02:29 INFO - 1500512549627 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49342/test.html"
18:02:29 INFO - 1500512549644 Marionette TRACE 358 <- [1,4,null,{}]
18:02:29 INFO - 1500512549659 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:02:29 INFO - 1500512549663 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"556c7eca-a333-4acc-bf46-60bbd6b6052f","ELEMENT":"556c7eca-a333-4acc-bf46-60bbd6b6052f"}}]
18:02:29 INFO - 1500512549664 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:02:29 INFO - 1500512549664 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:02:29 INFO - 1500512549667 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
18:02:29 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500512549670 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:02:29 INFO - 1500512549671 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:02:29 INFO - 1500512549672 Marionette TRACE 358 <- [1,8,null,{}]
18:02:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 206ms
18:02:29 INFO - TEST-START | test_click.py TestClick.test_container_element
18:02:29 INFO - 1500512549674 Marionette DEBUG Closed connection 358
18:02:29 INFO - 1500512549675 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49792
18:02:29 INFO - 1500512549676 Marionette DEBUG Closed connection 359
18:02:29 INFO - 1500512549677 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49793
18:02:29 INFO - 1500512549678 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:29 INFO - 1500512549678 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:29 INFO - 1500512549681 Marionette DEBUG Register listener.js for window 6442450945
18:02:29 INFO - 1500512549685 Marionette TRACE 360 <- [1,1,null,{"sessionId":"2535d06c-8ea4-4379-8b36-a17987d0a198","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:29 INFO - 1500512549686 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:02:29 INFO - 1500512549687 Marionette TRACE 360 <- [1,2,null,{}]
18:02:29 INFO - 1500512549688 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:02:29 INFO - 1500512549688 Marionette TRACE 360 <- [1,3,null,{}]
18:02:29 INFO - 1500512549689 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:02:29 INFO - 1500512549690 Marionette TRACE 360 <- [1,4,null,{}]
18:02:29 INFO - 1500512549692 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
18:02:29 INFO - TEST-START: test_click.py TestClick.test_container_element1500512549695 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:02:29 INFO - 1500512549696 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:02:29 INFO - 1500512549697 Marionette TRACE 360 <- [1,6,null,{}]
18:02:29 INFO - 1500512549699 Marionette DEBUG Closed connection 360
18:02:29 INFO - 1500512549700 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49794
18:02:29 INFO - 1500512549701 Marionette DEBUG Closed connection 361
18:02:29 INFO - 1500512549701 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49795
18:02:29 INFO - 1500512549702 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:29 INFO - 1500512549703 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:29 INFO - 1500512549706 Marionette DEBUG Register listener.js for window 6442450945
18:02:29 INFO - 1500512549709 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ef1aa148-b037-421d-aef5-a5c8e999c5fd","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:29 INFO - 1500512549710 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:02:29 INFO - 1500512549712 Marionette TRACE 362 <- [1,2,null,{}]
18:02:29 INFO - 1500512549714 Marionette DEBUG Closed connection 362
18:02:29 INFO - 1500512549714 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49796
18:02:29 INFO - 1500512549715 Marionette DEBUG Closed connection 363
18:02:29 INFO - 1500512549716 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49797
18:02:29 INFO - 1500512549717 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:02:29 INFO - 1500512549718 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:29 INFO - 1500512549721 Marionette DEBUG Register listener.js for window 6442450945
18:02:29 INFO - 1500512549724 Marionette TRACE 364 <- [1,1,null,{"sessionId":"87071def-950c-4135-a272-93c1b7e37990","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:29 INFO - 1500512549725 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:02:29 INFO - 1500512549727 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49342/test.html"
18:02:29 INFO - 1500512549731 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49342/test.html"
18:02:29 INFO - 1500512549731 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49342/test.html"
18:02:29 INFO - 1500512549740 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"
18:02:29 INFO - 1500512549746 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"
18:02:29 INFO - 1500512549755 Marionette TRACE 364 <- [1,2,null,{}]
18:02:29 INFO - 1500512549760 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:02:29 INFO - 1500512549763 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"56d15f81-6ce9-4310-8d1b-40dfa742ddfa","ELEMENT":"56d15f81-6ce9-4310-8d1b-40dfa742ddfa"}}]
18:02:29 INFO - 1500512549769 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"56d15f81-6ce9-4310-8d1b-40dfa742ddfa"}]
18:02:30 INFO - 1500512550018 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:02:30 INFO - 1500512550019 Marionette TRACE 364 <- [1,4,null,{}]
18:02:30 INFO - 1500512550020 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"56d15f81-6ce9-4310-8d1b-40dfa742ddfa","name":"selected"}]
18:02:30 INFO - 1500512550025 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:02:30 INFO - 1500512550027 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
18:02:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 361ms
18:02:30 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:02:30 INFO - TEST-END: test_click.py TestClick.test_container_element1500512550031 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:02:30 INFO - 1500512550032 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:02:30 INFO - 1500512550034 Marionette TRACE 364 <- [1,7,null,{}]
18:02:30 INFO - 1500512550035 Marionette DEBUG Closed connection 364
18:02:30 INFO - 1500512550036 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49798
18:02:30 INFO - 1500512550037 Marionette DEBUG Closed connection 365
18:02:30 INFO - 1500512550038 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49799
18:02:30 INFO - 1500512550039 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:30 INFO - 1500512550040 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550043 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550046 Marionette TRACE 366 <- [1,1,null,{"sessionId":"543545b1-62ad-41fe-afb6-ffe7d045a1cf","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550047 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:02:30 INFO - 1500512550048 Marionette TRACE 366 <- [1,2,null,{}]
18:02:30 INFO - 1500512550049 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:02:30 INFO - 1500512550049 Marionette TRACE 366 <- [1,3,null,{}]
18:02:30 INFO - 1500512550050 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:02:30 INFO - 1500512550051 Marionette TRACE 366 <- [1,4,null,{}]
18:02:30 INFO - 1500512550053 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
18:02:30 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500512550056 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:02:30 INFO - 1500512550057 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:02:30 INFO - 1500512550058 Marionette TRACE 366 <- [1,6,null,{}]
18:02:30 INFO - 1500512550060 Marionette DEBUG Closed connection 366
18:02:30 INFO - 1500512550060 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49800
18:02:30 INFO - 1500512550061 Marionette DEBUG Closed connection 367
18:02:30 INFO - 1500512550062 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49801
18:02:30 INFO - 1500512550063 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:30 INFO - 1500512550064 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550067 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550070 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d757de38-5929-439d-ae19-d74f3d80f7df","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550072 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:02:30 INFO - 1500512550073 Marionette TRACE 368 <- [1,2,null,{}]
18:02:30 INFO - 1500512550075 Marionette DEBUG Closed connection 368
18:02:30 INFO - 1500512550076 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49802
18:02:30 INFO - 1500512550077 Marionette DEBUG Closed connection 369
18:02:30 INFO - 1500512550077 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49803
18:02:30 INFO - 1500512550078 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:02:30 INFO - 1500512550079 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550082 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550085 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d19ac916-c4ee-4986-bd92-f1feada631e7","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550087 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:02:30 INFO - 1500512550089 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"
18:02:30 INFO - 1500512550091 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"
18:02:30 INFO - 1500512550091 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"
18:02:30 INFO - 1500512550105 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"
18:02:30 INFO - 1500512550108 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"
18:02:30 INFO - 1500512550121 Marionette TRACE 370 <- [1,2,null,{}]
18:02:30 INFO - 1500512550127 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:02:30 INFO - 1500512550135 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ae38401f-aa73-42c6-8360-2a8681a2f33a","ELEMENT":"ae38401f-aa73-42c6-8360-2a8681a2f33a"}}]
18:02:30 INFO - 1500512550136 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ae38401f-aa73-42c6-8360-2a8681a2f33a"}]
18:02:30 INFO - 1500512550381 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:02:30 INFO - 1500512550382 Marionette TRACE 370 <- [1,4,null,{}]
18:02:30 INFO - 1500512550383 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ae38401f-aa73-42c6-8360-2a8681a2f33a","name":"selected"}]
18:02:30 INFO - 1500512550388 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:02:30 INFO - 1500512550390 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
18:02:30 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500512550397 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:02:30 INFO - 1500512550398 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:02:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 367ms
18:02:30 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:02:30 INFO - 1500512550400 Marionette TRACE 370 <- [1,7,null,{}]
18:02:30 INFO - 1500512550402 Marionette DEBUG Closed connection 370
18:02:30 INFO - 1500512550403 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49804
18:02:30 INFO - 1500512550404 Marionette DEBUG Closed connection 371
18:02:30 INFO - 1500512550404 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49805
18:02:30 INFO - 1500512550406 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:30 INFO - 1500512550406 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550409 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550421 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ce08e18e-6778-447d-93b1-cacd2b3a2fea","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550422 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:02:30 INFO - 1500512550423 Marionette TRACE 372 <- [1,2,null,{}]
18:02:30 INFO - 1500512550424 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:02:30 INFO - 1500512550425 Marionette TRACE 372 <- [1,3,null,{}]
18:02:30 INFO - 1500512550425 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:02:30 INFO - 1500512550426 Marionette TRACE 372 <- [1,4,null,{}]
18:02:30 INFO - 1500512550428 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
18:02:30 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500512550431 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:02:30 INFO - 1500512550432 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:02:30 INFO - 1500512550434 Marionette TRACE 372 <- [1,6,null,{}]
18:02:30 INFO - 1500512550435 Marionette DEBUG Closed connection 372
18:02:30 INFO - 1500512550436 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49806
18:02:30 INFO - 1500512550437 Marionette DEBUG Closed connection 373
18:02:30 INFO - 1500512550438 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49807
18:02:30 INFO - 1500512550439 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:30 INFO - 1500512550440 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550443 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550446 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c4cb8dcb-8a85-4604-b730-34e4f1bf2121","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550448 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:02:30 INFO - 1500512550449 Marionette TRACE 374 <- [1,2,null,{}]
18:02:30 INFO - 1500512550451 Marionette DEBUG Closed connection 374
18:02:30 INFO - 1500512550452 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49808
18:02:30 INFO - 1500512550452 Marionette DEBUG Closed connection 375
18:02:30 INFO - 1500512550453 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49809
18:02:30 INFO - 1500512550454 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:02:30 INFO - 1500512550455 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550458 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550461 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8e455847-0253-488d-840b-990d4daac63c","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550463 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
18:02:30 INFO - 1500512550465 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"
18:02:30 INFO - 1500512550467 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"
18:02:30 INFO - 1500512550468 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"
18:02:30 INFO - 1500512550475 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"
18:02:30 INFO - 1500512550478 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"
18:02:30 INFO - 1500512550488 Marionette TRACE 376 <- [1,2,null,{}]
18:02:30 INFO - 1500512550492 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:02:30 INFO - 1500512550495 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"274c47bc-cc0a-4573-87f5-27578ceccdb2","ELEMENT":"274c47bc-cc0a-4573-87f5-27578ceccdb2"}}]
18:02:30 INFO - 1500512550502 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"274c47bc-cc0a-4573-87f5-27578ceccdb2"}]
18:02:30 INFO - 1500512550750 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:02:30 INFO - 1500512550751 Marionette TRACE 376 <- [1,4,null,{}]
18:02:30 INFO - 1500512550753 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
18:02:30 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500512550756 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:02:30 INFO - 1500512550757 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:02:30 INFO - 1500512550758 Marionette TRACE 376 <- [1,6,null,{}]
18:02:30 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 358ms
18:02:30 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:02:30 INFO - 1500512550760 Marionette DEBUG Closed connection 376
18:02:30 INFO - 1500512550761 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49810
18:02:30 INFO - 1500512550762 Marionette DEBUG Closed connection 377
18:02:30 INFO - 1500512550763 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49811
18:02:30 INFO - 1500512550764 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:30 INFO - 1500512550764 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550768 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550771 Marionette TRACE 378 <- [1,1,null,{"sessionId":"b0cd1695-2731-4cb3-ae40-19e6c309b731","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550772 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:02:30 INFO - 1500512550773 Marionette TRACE 378 <- [1,2,null,{}]
18:02:30 INFO - 1500512550774 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:02:30 INFO - 1500512550774 Marionette TRACE 378 <- [1,3,null,{}]
18:02:30 INFO - 1500512550775 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:02:30 INFO - 1500512550776 Marionette TRACE 378 <- [1,4,null,{}]
18:02:30 INFO - 1500512550778 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
18:02:30 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500512550781 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:02:30 INFO - 1500512550782 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:02:30 INFO - 1500512550783 Marionette TRACE 378 <- [1,6,null,{}]
18:02:30 INFO - 1500512550785 Marionette DEBUG Closed connection 378
18:02:30 INFO - 1500512550786 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49812
18:02:30 INFO - 1500512550786 Marionette DEBUG Closed connection 379
18:02:30 INFO - 1500512550787 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49813
18:02:30 INFO - 1500512550788 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:30 INFO - 1500512550789 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550792 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550795 Marionette TRACE 380 <- [1,1,null,{"sessionId":"278a22ba-e7dd-4a29-beec-5da843ff2661","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550796 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:02:30 INFO - 1500512550798 Marionette TRACE 380 <- [1,2,null,{}]
18:02:30 INFO - 1500512550800 Marionette DEBUG Closed connection 380
18:02:30 INFO - 1500512550800 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49814
18:02:30 INFO - 1500512550801 Marionette DEBUG Closed connection 381
18:02:30 INFO - 1500512550802 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49815
18:02:30 INFO - 1500512550803 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:02:30 INFO - 1500512550804 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:30 INFO - 1500512550806 Marionette DEBUG Register listener.js for window 6442450945
18:02:30 INFO - 1500512550810 Marionette TRACE 382 <- [1,1,null,{"sessionId":"41925aff-34db-49ca-9d4b-808be4a8031e","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:30 INFO - 1500512550811 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
18:02:30 INFO - 1500512550813 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"
18:02:30 INFO - 1500512550815 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"
18:02:30 INFO - 1500512550815 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"
18:02:30 INFO - 1500512550822 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"
18:02:30 INFO - 1500512550826 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"
18:02:30 INFO - 1500512550830 Marionette TRACE 382 <- [1,2,null,{}]
18:02:30 INFO - 1500512550841 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:02:30 INFO - 1500512550844 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6686af45-1dbb-4e2a-a06e-151a070eff51","ELEMENT":"6686af45-1dbb-4e2a-a06e-151a070eff51"}}]
18:02:30 INFO - 1500512550845 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"6686af45-1dbb-4e2a-a06e-151a070eff51"}]
18:02:31 INFO - 1500512551090 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:02:31 INFO - 1500512551091 Marionette TRACE 382 <- [1,4,null,{}]
18:02:31 INFO - 1500512551092 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
18:02:31 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500512551096 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:02:31 INFO - 1500512551097 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:02:31 INFO - 1500512551098 Marionette TRACE 382 <- [1,6,null,{}]
18:02:31 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 340ms
18:02:31 INFO - TEST-START | test_click.py TestClick.test_input_file
18:02:31 INFO - 1500512551100 Marionette DEBUG Closed connection 382
18:02:31 INFO - 1500512551101 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49816
18:02:31 INFO - 1500512551102 Marionette DEBUG Closed connection 383
18:02:31 INFO - 1500512551102 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49817
18:02:31 INFO - 1500512551104 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:31 INFO - 1500512551104 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551107 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551111 Marionette TRACE 384 <- [1,1,null,{"sessionId":"2a2463bb-a3d2-4e98-a3a3-5f8b63d3e324","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551112 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:02:31 INFO - 1500512551113 Marionette TRACE 384 <- [1,2,null,{}]
18:02:31 INFO - 1500512551114 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:02:31 INFO - 1500512551114 Marionette TRACE 384 <- [1,3,null,{}]
18:02:31 INFO - 1500512551115 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:02:31 INFO - 1500512551116 Marionette TRACE 384 <- [1,4,null,{}]
18:02:31 INFO - 1500512551117 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
18:02:31 INFO - TEST-START: test_click.py TestClick.test_input_file1500512551120 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:02:31 INFO - 1500512551121 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:02:31 INFO - 1500512551123 Marionette TRACE 384 <- [1,6,null,{}]
18:02:31 INFO - 1500512551125 Marionette DEBUG Closed connection 384
18:02:31 INFO - 1500512551125 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49818
18:02:31 INFO - 1500512551126 Marionette DEBUG Closed connection 385
18:02:31 INFO - 1500512551127 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49819
18:02:31 INFO - 1500512551128 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:31 INFO - 1500512551129 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551132 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551135 Marionette TRACE 386 <- [1,1,null,{"sessionId":"815d1409-e105-41e6-bbc9-a8eecb65019b","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551136 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:02:31 INFO - 1500512551138 Marionette TRACE 386 <- [1,2,null,{}]
18:02:31 INFO - 1500512551140 Marionette DEBUG Closed connection 386
18:02:31 INFO - 1500512551140 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49820
18:02:31 INFO - 1500512551141 Marionette DEBUG Closed connection 387
18:02:31 INFO - 1500512551142 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49821
18:02:31 INFO - 1500512551143 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:02:31 INFO - 1500512551144 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551147 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551150 Marionette TRACE 388 <- [1,1,null,{"sessionId":"407fa4e9-61aa-4a6e-81d7-7db77e2b5268","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551151 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:02:31 INFO - 1500512551153 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"
18:02:31 INFO - 1500512551155 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"
18:02:31 INFO - 1500512551156 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"
18:02:31 INFO - 1500512551163 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:02:31 INFO - 1500512551169 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:02:31 INFO - 1500512551176 Marionette TRACE 388 <- [1,2,null,{}]
18:02:31 INFO - 1500512551185 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:02:31 INFO - 1500512551188 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8f11016d-a560-4ddf-b4ca-3937c0cfb23b","ELEMENT":"8f11016d-a560-4ddf-b4ca-3937c0cfb23b"}}]
18:02:31 INFO - 1500512551189 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"8f11016d-a560-4ddf-b4ca-3937c0cfb23b"}]
18:02:31 INFO - 1500512551224 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:02:31 INFO - 1500512551226 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
18:02:31 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 135ms
18:02:31 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:02:31 INFO - TEST-END: test_click.py TestClick.test_input_file1500512551231 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:02:31 INFO - 1500512551232 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:02:31 INFO - 1500512551233 Marionette TRACE 388 <- [1,6,null,{}]
18:02:31 INFO - 1500512551235 Marionette DEBUG Closed connection 388
18:02:31 INFO - 1500512551236 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49822
18:02:31 INFO - 1500512551237 Marionette DEBUG Closed connection 389
18:02:31 INFO - 1500512551238 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49823
18:02:31 INFO - 1500512551239 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:31 INFO - 1500512551239 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551242 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551246 Marionette TRACE 390 <- [1,1,null,{"sessionId":"41079fb2-6500-4c4a-8416-948f38e38b9e","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551247 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:02:31 INFO - 1500512551248 Marionette TRACE 390 <- [1,2,null,{}]
18:02:31 INFO - 1500512551249 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:02:31 INFO - 1500512551249 Marionette TRACE 390 <- [1,3,null,{}]
18:02:31 INFO - 1500512551250 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:02:31 INFO - 1500512551251 Marionette TRACE 390 <- [1,4,null,{}]
18:02:31 INFO - 1500512551253 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
18:02:31 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500512551256 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:02:31 INFO - 1500512551257 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:02:31 INFO - 1500512551259 Marionette TRACE 390 <- [1,6,null,{}]
18:02:31 INFO - 1500512551260 Marionette DEBUG Closed connection 390
18:02:31 INFO - 1500512551261 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49824
18:02:31 INFO - 1500512551262 Marionette DEBUG Closed connection 391
18:02:31 INFO - 1500512551262 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49825
18:02:31 INFO - 1500512551264 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:31 INFO - 1500512551264 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551267 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551270 Marionette TRACE 392 <- [1,1,null,{"sessionId":"32f535d0-5d65-4e00-8241-e939e6c5a095","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551272 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:02:31 INFO - 1500512551273 Marionette TRACE 392 <- [1,2,null,{}]
18:02:31 INFO - 1500512551275 Marionette DEBUG Closed connection 392
18:02:31 INFO - 1500512551276 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49826
18:02:31 INFO - 1500512551276 Marionette DEBUG Closed connection 393
18:02:31 INFO - 1500512551277 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49827
18:02:31 INFO - 1500512551278 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:02:31 INFO - 1500512551279 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551282 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551286 Marionette TRACE 394 <- [1,1,null,{"sessionId":"609f3241-1edd-4475-9fb4-3f39bbc9bd27","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551287 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
18:02:31 INFO - 1500512551289 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:02:31 INFO - 1500512551291 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:02:31 INFO - 1500512551291 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:02:31 INFO - 1500512551301 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"
18:02:31 INFO - 1500512551303 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"
18:02:31 INFO - 1500512551311 Marionette TRACE 394 <- [1,2,null,{}]
18:02:31 INFO - 1500512551320 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:02:31 INFO - 1500512551325 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4878336f-f96a-4faa-81e2-8591d4dbb7b3","ELEMENT":"4878336f-f96a-4faa-81e2-8591d4dbb7b3"}}]
18:02:31 INFO - 1500512551326 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:02:31 INFO - 1500512551334 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c13f4c2-4731-4bf5-a0c1-ebebb4a147fd","ELEMENT":"6c13f4c2-4731-4bf5-a0c1-ebebb4a147fd"}}]
18:02:31 INFO - 1500512551335 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"4878336f-f96a-4faa-81e2-8591d4dbb7b3"}]
18:02:31 INFO - 1500512551577 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:02:31 INFO - 1500512551578 Marionette TRACE 394 <- [1,5,null,{}]
18:02:31 INFO - 1500512551579 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"6c13f4c2-4731-4bf5-a0c1-ebebb4a147fd"}]
18:02:31 INFO - 1500512551598 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
18:02:31 INFO - 1500512551600 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
18:02:31 INFO - 1500512551604 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:02:31 INFO - 1500512551605 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
18:02:31 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500512551608 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:02:31 INFO - 1500512551609 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:02:31 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 378ms
18:02:31 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:02:31 INFO - 1500512551611 Marionette TRACE 394 <- [1,9,null,{}]
18:02:31 INFO - 1500512551613 Marionette DEBUG Closed connection 394
18:02:31 INFO - 1500512551614 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49828
18:02:31 INFO - 1500512551615 Marionette DEBUG Closed connection 395
18:02:31 INFO - 1500512551616 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49829
18:02:31 INFO - 1500512551617 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:31 INFO - 1500512551617 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551620 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551624 Marionette TRACE 396 <- [1,1,null,{"sessionId":"f9fddfff-1a13-4af3-bf1b-8607f4245933","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551625 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:02:31 INFO - 1500512551626 Marionette TRACE 396 <- [1,2,null,{}]
18:02:31 INFO - 1500512551627 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:02:31 INFO - 1500512551627 Marionette TRACE 396 <- [1,3,null,{}]
18:02:31 INFO - 1500512551628 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:02:31 INFO - 1500512551629 Marionette TRACE 396 <- [1,4,null,{}]
18:02:31 INFO - 1500512551631 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
18:02:31 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500512551634 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:02:31 INFO - 1500512551635 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:02:31 INFO - 1500512551636 Marionette TRACE 396 <- [1,6,null,{}]
18:02:31 INFO - 1500512551638 Marionette DEBUG Closed connection 396
18:02:31 INFO - 1500512551638 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49830
18:02:31 INFO - 1500512551639 Marionette DEBUG Closed connection 397
18:02:31 INFO - 1500512551640 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49831
18:02:31 INFO - 1500512551641 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:02:31 INFO - 1500512551642 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551645 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551649 Marionette TRACE 398 <- [1,1,null,{"sessionId":"8832ca0a-1678-4eec-9009-e81ad74eb072","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551650 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:02:31 INFO - 1500512551651 Marionette TRACE 398 <- [1,2,null,{}]
18:02:31 INFO - 1500512551653 Marionette DEBUG Closed connection 398
18:02:31 INFO - 1500512551654 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49832
18:02:31 INFO - 1500512551655 Marionette DEBUG Closed connection 399
18:02:31 INFO - 1500512551655 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49833
18:02:31 INFO - 1500512551656 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:02:31 INFO - 1500512551657 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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}
18:02:31 INFO - 1500512551660 Marionette DEBUG Register listener.js for window 6442450945
18:02:31 INFO - 1500512551663 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b956ccf6-556d-4c60-8e2f-ff214dbaf6d1","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":5792,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpeya2dg.mozrunner","moz:accessibilityChecks":false}}]
18:02:31 INFO - 1500512551665 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
18:02:31 INFO - 1500512551666 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"
18:02:31 INFO - 1500512551669 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"
18:02:31 INFO - 1500512551669 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"
18:02:31 INFO - 1500512551676 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"
18:02:31 INFO - 1500512551679 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"
18:02:31 INFO - 1500512551689 Marionette TRACE 400 <- [1,2,null,{}]
18:02:31 INFO - 1500512551693 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:02:31 INFO - 1500512551696 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"77f1d4c5-8d92-4c33-a496-4bddc0f9ba52","ELEMENT":"77f1d4c5-8d92-4c33-a496-4bddc0f9ba52"}}]
18:02:31 INFO - 1500512551702 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"77f1d4c5-8d92-4c33-a496-4bddc0f9ba52"}]
18:02:31 INFO - 1500512551726 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:02:31 INFO - 1500512551727 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"77f1d4c5-8d92-4c33-a496-4bddc0f9ba52"}]
18:02:31 INFO - 1500512551746 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element