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]
12:38:20 INFO - 1500665900519 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}]
12:38:20 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500665900522 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:38:20 INFO - 1500665900523 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:38:20 INFO - 1500665900524 Marionette TRACE 352 <- [1,6,null,{}]
12:38:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 126ms
12:38:20 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:38:20 INFO - 1500665900548 Marionette DEBUG Closed connection 352
12:38:20 INFO - 1500665900548 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49857
12:38:20 INFO - 1500665900551 Marionette DEBUG Closed connection 353
12:38:20 INFO - 1500665900551 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49858
12:38:20 INFO - 1500665900552 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:20 INFO - 1500665900553 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:20 INFO - 1500665900555 Marionette DEBUG Register listener.js for window 15
12:38:20 INFO - 1500665900558 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1a7d7119-50a7-4865-9c9e-f81c5e4494bc","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:20 INFO - 1500665900567 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:38:20 INFO - 1500665900568 Marionette TRACE 354 <- [1,2,null,{}]
12:38:20 INFO - 1500665900569 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:20 INFO - 1500665900569 Marionette TRACE 354 <- [1,3,null,{}]
12:38:20 INFO - 1500665900570 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:20 INFO - 1500665900571 Marionette TRACE 354 <- [1,4,null,{}]
12:38:20 INFO - 1500665900572 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}]
12:38:20 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500665900575 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:38:20 INFO - 1500665900576 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:38:20 INFO - 1500665900577 Marionette TRACE 354 <- [1,6,null,{}]
12:38:20 INFO - 1500665900579 Marionette DEBUG Closed connection 354
12:38:20 INFO - 1500665900580 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49859
12:38:20 INFO - 1500665900580 Marionette DEBUG Closed connection 355
12:38:20 INFO - 1500665900581 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49860
12:38:20 INFO - 1500665900582 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:20 INFO - 1500665900583 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:20 INFO - 1500665900585 Marionette DEBUG Register listener.js for window 15
12:38:20 INFO - 1500665900587 Marionette TRACE 356 <- [1,1,null,{"sessionId":"e669631d-f10f-4d2e-a8ff-f0eff40dee0c","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:20 INFO - 1500665900589 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:38:20 INFO - 1500665900590 Marionette TRACE 356 <- [1,2,null,{}]
12:38:20 INFO - 1500665900592 Marionette DEBUG Closed connection 356
12:38:20 INFO - 1500665900593 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49861
12:38:20 INFO - 1500665900593 Marionette DEBUG Closed connection 357
12:38:20 INFO - 1500665900594 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49862
12:38:20 INFO - 1500665900595 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:20 INFO - 1500665900596 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:20 INFO - 1500665900597 Marionette DEBUG Register listener.js for window 15
12:38:20 INFO - 1500665900600 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a6712539-4181-4870-8485-990fc2f6a278","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:20 INFO - 1500665900602 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49425/clicks.html"}]
12:38:20 INFO - 1500665900603 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49425/hidden.html"
12:38:20 INFO - 1500665900610 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49425/hidden.html"
12:38:20 INFO - 1500665900610 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49425/hidden.html"
12:38:20 INFO - 1500665900624 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49425/clicks.html"
12:38:20 INFO - 1500665900630 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49425/clicks.html"
12:38:20 INFO - 1500665900633 Marionette TRACE 358 <- [1,2,null,{}]
12:38:20 INFO - 1500665900635 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:38:20 INFO - 1500665900637 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9bdd8889-b263-42af-9ae1-018bcf47f0bf","ELEMENT":"9bdd8889-b263-42af-9ae1-018bcf47f0bf"}}]
12:38:20 INFO - 1500665900638 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"9bdd8889-b263-42af-9ae1-018bcf47f0bf"}]
12:38:20 INFO - 1500665900683 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49425/clicks.html"
12:38:20 INFO - 1500665900688 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49425/clicks.html"
12:38:20 INFO - 1500665900689 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49425/clicks.html"
12:38:20 INFO - 1500665900709 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49425/test.html"
12:38:20 INFO - 1500665900711 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49425/test.html"
12:38:20 INFO - 1500665900714 Marionette TRACE 358 <- [1,4,null,{}]
12:38:20 INFO - 1500665900717 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:38:20 INFO - 1500665900720 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f431131-1d37-403c-bc74-d24749efcf2a","ELEMENT":"4f431131-1d37-403c-bc74-d24749efcf2a"}}]
12:38:20 INFO - 1500665900721 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:38:20 INFO - 1500665900722 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:38:20 INFO - 1500665900729 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}]
12:38:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500665900732 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:38:20 INFO - 1500665900733 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:38:20 INFO - 1500665900734 Marionette TRACE 358 <- [1,8,null,{}]
12:38:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 210ms
12:38:20 INFO - TEST-START | test_click.py TestClick.test_container_element
12:38:20 INFO - 1500665900736 Marionette DEBUG Closed connection 358
12:38:20 INFO - 1500665900737 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49865
12:38:20 INFO - 1500665900737 Marionette DEBUG Closed connection 359
12:38:20 INFO - 1500665900738 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49866
12:38:20 INFO - 1500665900739 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:20 INFO - 1500665900739 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:20 INFO - 1500665900742 Marionette DEBUG Register listener.js for window 15
12:38:20 INFO - 1500665900745 Marionette TRACE 360 <- [1,1,null,{"sessionId":"161ae341-de82-492a-b23f-a818614566e5","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:20 INFO - 1500665900748 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:38:20 INFO - 1500665900748 Marionette TRACE 360 <- [1,2,null,{}]
12:38:20 INFO - 1500665900754 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:20 INFO - 1500665900754 Marionette TRACE 360 <- [1,3,null,{}]
12:38:20 INFO - 1500665900755 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:20 INFO - 1500665900755 Marionette TRACE 360 <- [1,4,null,{}]
12:38:20 INFO - 1500665900760 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}]
12:38:20 INFO - TEST-START: test_click.py TestClick.test_container_element1500665900762 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:38:20 INFO - 1500665900763 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:38:20 INFO - 1500665900764 Marionette TRACE 360 <- [1,6,null,{}]
12:38:20 INFO - 1500665900766 Marionette DEBUG Closed connection 360
12:38:20 INFO - 1500665900767 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49867
12:38:20 INFO - 1500665900767 Marionette DEBUG Closed connection 361
12:38:20 INFO - 1500665900768 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49868
12:38:20 INFO - 1500665900769 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:20 INFO - 1500665900770 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:20 INFO - 1500665900772 Marionette DEBUG Register listener.js for window 15
12:38:20 INFO - 1500665900775 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b65f0737-c165-43db-a5ba-91a3a281aad2","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:20 INFO - 1500665900778 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:38:20 INFO - 1500665900779 Marionette TRACE 362 <- [1,2,null,{}]
12:38:20 INFO - 1500665900781 Marionette DEBUG Closed connection 362
12:38:20 INFO - 1500665900781 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49869
12:38:20 INFO - 1500665900782 Marionette DEBUG Closed connection 363
12:38:20 INFO - 1500665900783 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49870
12:38:20 INFO - 1500665900784 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:20 INFO - 1500665900784 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:20 INFO - 1500665900786 Marionette DEBUG Register listener.js for window 15
12:38:20 INFO - 1500665900789 Marionette TRACE 364 <- [1,1,null,{"sessionId":"080440cd-a8e2-4a4c-aa12-7317e12458d1","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:20 INFO - 1500665900792 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"}]
12:38:20 INFO - 1500665900793 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49425/test.html"
12:38:20 INFO - 1500665900796 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49425/test.html"
12:38:20 INFO - 1500665900796 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49425/test.html"
12:38:20 INFO - 1500665900825 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"
12:38:20 INFO - 1500665900829 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"
12:38:20 INFO - 1500665900833 Marionette TRACE 364 <- [1,2,null,{}]
12:38:20 INFO - 1500665900835 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:38:20 INFO - 1500665900837 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"73e26b53-c48a-4c72-a4c9-32e4c419c5b8","ELEMENT":"73e26b53-c48a-4c72-a4c9-32e4c419c5b8"}}]
12:38:20 INFO - 1500665900838 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"73e26b53-c48a-4c72-a4c9-32e4c419c5b8"}]
12:38:21 INFO - 1500665901082 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:21 INFO - 1500665901083 Marionette TRACE 364 <- [1,4,null,{}]
12:38:21 INFO - 1500665901084 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"73e26b53-c48a-4c72-a4c9-32e4c419c5b8","name":"selected"}]
12:38:21 INFO - 1500665901088 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:38:21 INFO - 1500665901090 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}]
12:38:21 INFO - TEST-END: test_click.py TestClick.test_container_element1500665901093 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:38:21 INFO - 1500665901094 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:38:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 361ms
12:38:21 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:38:21 INFO - 1500665901096 Marionette TRACE 364 <- [1,7,null,{}]
12:38:21 INFO - 1500665901097 Marionette DEBUG Closed connection 364
12:38:21 INFO - 1500665901098 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49871
12:38:21 INFO - 1500665901099 Marionette DEBUG Closed connection 365
12:38:21 INFO - 1500665901100 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49872
12:38:21 INFO - 1500665901101 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:21 INFO - 1500665901102 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901104 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901106 Marionette TRACE 366 <- [1,1,null,{"sessionId":"eec3b17d-356f-4d61-be3c-95cbfc102e9d","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901108 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:38:21 INFO - 1500665901108 Marionette TRACE 366 <- [1,2,null,{}]
12:38:21 INFO - 1500665901109 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:21 INFO - 1500665901110 Marionette TRACE 366 <- [1,3,null,{}]
12:38:21 INFO - 1500665901110 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:21 INFO - 1500665901111 Marionette TRACE 366 <- [1,4,null,{}]
12:38:21 INFO - 1500665901113 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}]
12:38:21 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500665901115 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:38:21 INFO - 1500665901116 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:38:21 INFO - 1500665901117 Marionette TRACE 366 <- [1,6,null,{}]
12:38:21 INFO - 1500665901119 Marionette DEBUG Closed connection 366
12:38:21 INFO - 1500665901120 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49873
12:38:21 INFO - 1500665901120 Marionette DEBUG Closed connection 367
12:38:21 INFO - 1500665901121 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49874
12:38:21 INFO - 1500665901122 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:21 INFO - 1500665901122 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901131 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901134 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e56a7bbb-9e3b-4954-a7e9-1ae494f20bcd","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901136 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:38:21 INFO - 1500665901137 Marionette TRACE 368 <- [1,2,null,{}]
12:38:21 INFO - 1500665901139 Marionette DEBUG Closed connection 368
12:38:21 INFO - 1500665901140 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49875
12:38:21 INFO - 1500665901141 Marionette DEBUG Closed connection 369
12:38:21 INFO - 1500665901141 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49876
12:38:21 INFO - 1500665901142 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:21 INFO - 1500665901143 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901145 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901147 Marionette TRACE 370 <- [1,1,null,{"sessionId":"75b19d95-7b72-43fa-bd31-aaab9d0f3693","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901149 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"}]
12:38:21 INFO - 1500665901151 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"
12:38:21 INFO - 1500665901152 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"
12:38:21 INFO - 1500665901153 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"
12:38:21 INFO - 1500665901169 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"
12:38:21 INFO - 1500665901171 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"
12:38:21 INFO - 1500665901174 Marionette TRACE 370 <- [1,2,null,{}]
12:38:21 INFO - 1500665901180 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:38:21 INFO - 1500665901182 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1008aefc-5b74-44f1-8095-2db16ae1af4e","ELEMENT":"1008aefc-5b74-44f1-8095-2db16ae1af4e"}}]
12:38:21 INFO - 1500665901183 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1008aefc-5b74-44f1-8095-2db16ae1af4e"}]
12:38:21 INFO - 1500665901420 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:21 INFO - 1500665901421 Marionette TRACE 370 <- [1,4,null,{}]
12:38:21 INFO - 1500665901422 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1008aefc-5b74-44f1-8095-2db16ae1af4e","name":"selected"}]
12:38:21 INFO - 1500665901429 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:38:21 INFO - 1500665901431 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}]
12:38:21 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500665901433 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:38:21 INFO - 1500665901434 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:38:21 INFO - 1500665901435 Marionette TRACE 370 <- [1,7,null,{}]
12:38:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 339ms
12:38:21 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:38:21 INFO - 1500665901437 Marionette DEBUG Closed connection 370
12:38:21 INFO - 1500665901438 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49877
12:38:21 INFO - 1500665901439 Marionette DEBUG Closed connection 371
12:38:21 INFO - 1500665901440 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49878
12:38:21 INFO - 1500665901441 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:21 INFO - 1500665901441 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901443 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901446 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3233b201-cc71-4d59-bf48-2a40017a4b8b","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901447 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:38:21 INFO - 1500665901448 Marionette TRACE 372 <- [1,2,null,{}]
12:38:21 INFO - 1500665901449 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:21 INFO - 1500665901449 Marionette TRACE 372 <- [1,3,null,{}]
12:38:21 INFO - 1500665901450 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:21 INFO - 1500665901450 Marionette TRACE 372 <- [1,4,null,{}]
12:38:21 INFO - 1500665901452 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}]
12:38:21 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500665901454 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:38:21 INFO - 1500665901455 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:38:21 INFO - 1500665901456 Marionette TRACE 372 <- [1,6,null,{}]
12:38:21 INFO - 1500665901458 Marionette DEBUG Closed connection 372
12:38:21 INFO - 1500665901458 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49879
12:38:21 INFO - 1500665901459 Marionette DEBUG Closed connection 373
12:38:21 INFO - 1500665901460 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49880
12:38:21 INFO - 1500665901461 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:21 INFO - 1500665901461 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901463 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901465 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c6fd00d8-db59-4daf-9413-81922170dfd1","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901467 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:38:21 INFO - 1500665901468 Marionette TRACE 374 <- [1,2,null,{}]
12:38:21 INFO - 1500665901470 Marionette DEBUG Closed connection 374
12:38:21 INFO - 1500665901470 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49881
12:38:21 INFO - 1500665901471 Marionette DEBUG Closed connection 375
12:38:21 INFO - 1500665901472 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49882
12:38:21 INFO - 1500665901473 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:21 INFO - 1500665901474 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901475 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901478 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e41cb0b3-160b-4951-8da1-dfad0ad3e0a9","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901480 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"}]
12:38:21 INFO - 1500665901482 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"
12:38:21 INFO - 1500665901483 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"
12:38:21 INFO - 1500665901484 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"
12:38:21 INFO - 1500665901500 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"
12:38:21 INFO - 1500665901502 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"
12:38:21 INFO - 1500665901505 Marionette TRACE 376 <- [1,2,null,{}]
12:38:21 INFO - 1500665901512 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:38:21 INFO - 1500665901515 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c27e0944-76be-4840-9f22-cd759ca04901","ELEMENT":"c27e0944-76be-4840-9f22-cd759ca04901"}}]
12:38:21 INFO - 1500665901516 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"c27e0944-76be-4840-9f22-cd759ca04901"}]
12:38:21 INFO - 1500665901751 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:21 INFO - 1500665901752 Marionette TRACE 376 <- [1,4,null,{}]
12:38:21 INFO - 1500665901754 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}]
12:38:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500665901756 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:38:21 INFO - 1500665901757 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:38:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 323ms
12:38:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:38:21 INFO - 1500665901758 Marionette TRACE 376 <- [1,6,null,{}]
12:38:21 INFO - 1500665901760 Marionette DEBUG Closed connection 376
12:38:21 INFO - 1500665901761 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49883
12:38:21 INFO - 1500665901762 Marionette DEBUG Closed connection 377
12:38:21 INFO - 1500665901763 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49884
12:38:21 INFO - 1500665901764 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:21 INFO - 1500665901764 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901766 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901769 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ea7cb666-39f4-4b3a-9b16-fd7047a2ce1f","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901770 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:38:21 INFO - 1500665901771 Marionette TRACE 378 <- [1,2,null,{}]
12:38:21 INFO - 1500665901772 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:21 INFO - 1500665901772 Marionette TRACE 378 <- [1,3,null,{}]
12:38:21 INFO - 1500665901773 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:21 INFO - 1500665901774 Marionette TRACE 378 <- [1,4,null,{}]
12:38:21 INFO - 1500665901775 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}]
12:38:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500665901777 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:38:21 INFO - 1500665901778 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:38:21 INFO - 1500665901779 Marionette TRACE 378 <- [1,6,null,{}]
12:38:21 INFO - 1500665901781 Marionette DEBUG Closed connection 378
12:38:21 INFO - 1500665901782 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49885
12:38:21 INFO - 1500665901782 Marionette DEBUG Closed connection 379
12:38:21 INFO - 1500665901783 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49886
12:38:21 INFO - 1500665901784 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:21 INFO - 1500665901785 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901786 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901796 Marionette TRACE 380 <- [1,1,null,{"sessionId":"7791c637-c046-497c-8d0d-277c8bd3af19","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901798 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:38:21 INFO - 1500665901799 Marionette TRACE 380 <- [1,2,null,{}]
12:38:21 INFO - 1500665901801 Marionette DEBUG Closed connection 380
12:38:21 INFO - 1500665901801 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49887
12:38:21 INFO - 1500665901802 Marionette DEBUG Closed connection 381
12:38:21 INFO - 1500665901803 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49888
12:38:21 INFO - 1500665901804 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:21 INFO - 1500665901804 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:21 INFO - 1500665901806 Marionette DEBUG Register listener.js for window 15
12:38:21 INFO - 1500665901809 Marionette TRACE 382 <- [1,1,null,{"sessionId":"e3430400-770b-49f5-ad9a-4b71f525c956","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:21 INFO - 1500665901811 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"}]
12:38:21 INFO - 1500665901812 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"
12:38:21 INFO - 1500665901813 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"
12:38:21 INFO - 1500665901814 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"
12:38:21 INFO - 1500665901830 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"
12:38:21 INFO - 1500665901831 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"
12:38:21 INFO - 1500665901836 Marionette TRACE 382 <- [1,2,null,{}]
12:38:21 INFO - 1500665901837 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:38:21 INFO - 1500665901845 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c646460e-88be-4e16-a929-a94394d505c2","ELEMENT":"c646460e-88be-4e16-a929-a94394d505c2"}}]
12:38:21 INFO - 1500665901846 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c646460e-88be-4e16-a929-a94394d505c2"}]
12:38:22 INFO - 1500665902084 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:22 INFO - 1500665902085 Marionette TRACE 382 <- [1,4,null,{}]
12:38:22 INFO - 1500665902087 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}]
12:38:22 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500665902090 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:38:22 INFO - 1500665902090 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:38:22 INFO - 1500665902092 Marionette TRACE 382 <- [1,6,null,{}]
12:38:22 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 333ms
12:38:22 INFO - TEST-START | test_click.py TestClick.test_input_file
12:38:22 INFO - 1500665902094 Marionette DEBUG Closed connection 382
12:38:22 INFO - 1500665902095 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49889
12:38:22 INFO - 1500665902095 Marionette DEBUG Closed connection 383
12:38:22 INFO - 1500665902096 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49890
12:38:22 INFO - 1500665902097 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:22 INFO - 1500665902098 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902100 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902103 Marionette TRACE 384 <- [1,1,null,{"sessionId":"27308f21-c855-4fe0-8e2b-b562f92339a2","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902105 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:38:22 INFO - 1500665902105 Marionette TRACE 384 <- [1,2,null,{}]
12:38:22 INFO - 1500665902106 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:22 INFO - 1500665902106 Marionette TRACE 384 <- [1,3,null,{}]
12:38:22 INFO - 1500665902107 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:22 INFO - 1500665902108 Marionette TRACE 384 <- [1,4,null,{}]
12:38:22 INFO - 1500665902109 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}]
12:38:22 INFO - TEST-START: test_click.py TestClick.test_input_file1500665902112 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:38:22 INFO - 1500665902113 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:38:22 INFO - 1500665902114 Marionette TRACE 384 <- [1,6,null,{}]
12:38:22 INFO - 1500665902116 Marionette DEBUG Closed connection 384
12:38:22 INFO - 1500665902116 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49891
12:38:22 INFO - 1500665902117 Marionette DEBUG Closed connection 385
12:38:22 INFO - 1500665902118 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49892
12:38:22 INFO - 1500665902119 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:22 INFO - 1500665902119 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902121 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902124 Marionette TRACE 386 <- [1,1,null,{"sessionId":"25e28e35-b3c4-41e1-ab31-3af5e972acc5","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902125 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:38:22 INFO - 1500665902126 Marionette TRACE 386 <- [1,2,null,{}]
12:38:22 INFO - 1500665902128 Marionette DEBUG Closed connection 386
12:38:22 INFO - 1500665902129 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49893
12:38:22 INFO - 1500665902129 Marionette DEBUG Closed connection 387
12:38:22 INFO - 1500665902130 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49894
12:38:22 INFO - 1500665902131 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:22 INFO - 1500665902132 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902133 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902136 Marionette TRACE 388 <- [1,1,null,{"sessionId":"18ba10d4-17f5-44cd-9cb6-d5ed423fce4a","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902138 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:38:22 INFO - 1500665902139 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"
12:38:22 INFO - 1500665902141 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"
12:38:22 INFO - 1500665902141 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"
12:38:22 INFO - 1500665902151 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:22 INFO - 1500665902155 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:22 INFO - 1500665902165 Marionette TRACE 388 <- [1,2,null,{}]
12:38:22 INFO - 1500665902166 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:38:22 INFO - 1500665902169 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"528d7052-6272-4f9e-9e2e-9079d90889d2","ELEMENT":"528d7052-6272-4f9e-9e2e-9079d90889d2"}}]
12:38:22 INFO - 1500665902170 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"528d7052-6272-4f9e-9e2e-9079d90889d2"}]
12:38:22 INFO - 1500665902195 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]
12:38:22 INFO - 1500665902197 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}]
12:38:22 INFO - TEST-END: test_click.py TestClick.test_input_file1500665902199 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:38:22 INFO - 1500665902200 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:38:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 110ms
12:38:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:38:22 INFO - 1500665902201 Marionette TRACE 388 <- [1,6,null,{}]
12:38:22 INFO - 1500665902203 Marionette DEBUG Closed connection 388
12:38:22 INFO - 1500665902204 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49895
12:38:22 INFO - 1500665902205 Marionette DEBUG Closed connection 389
12:38:22 INFO - 1500665902206 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49896
12:38:22 INFO - 1500665902207 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:22 INFO - 1500665902207 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902209 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902212 Marionette TRACE 390 <- [1,1,null,{"sessionId":"a02ce01e-c9fe-4d97-bee3-a91ea1c628f8","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902214 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:38:22 INFO - 1500665902214 Marionette TRACE 390 <- [1,2,null,{}]
12:38:22 INFO - 1500665902215 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:22 INFO - 1500665902216 Marionette TRACE 390 <- [1,3,null,{}]
12:38:22 INFO - 1500665902216 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:22 INFO - 1500665902217 Marionette TRACE 390 <- [1,4,null,{}]
12:38:22 INFO - 1500665902219 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}]
12:38:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500665902221 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:38:22 INFO - 1500665902222 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:38:22 INFO - 1500665902223 Marionette TRACE 390 <- [1,6,null,{}]
12:38:22 INFO - 1500665902225 Marionette DEBUG Closed connection 390
12:38:22 INFO - 1500665902225 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49897
12:38:22 INFO - 1500665902226 Marionette DEBUG Closed connection 391
12:38:22 INFO - 1500665902227 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49898
12:38:22 INFO - 1500665902228 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:22 INFO - 1500665902228 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902230 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902233 Marionette TRACE 392 <- [1,1,null,{"sessionId":"e650df18-95cc-4f74-9c2e-dbc1d2bc8766","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902235 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:38:22 INFO - 1500665902236 Marionette TRACE 392 <- [1,2,null,{}]
12:38:22 INFO - 1500665902238 Marionette DEBUG Closed connection 392
12:38:22 INFO - 1500665902238 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49899
12:38:22 INFO - 1500665902239 Marionette DEBUG Closed connection 393
12:38:22 INFO - 1500665902240 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49900
12:38:22 INFO - 1500665902241 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:22 INFO - 1500665902241 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902243 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902253 Marionette TRACE 394 <- [1,1,null,{"sessionId":"31b7b960-f54e-40fb-a365-6e523d9ccc1d","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902255 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"}]
12:38:22 INFO - 1500665902257 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:22 INFO - 1500665902259 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:22 INFO - 1500665902259 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:38:22 INFO - 1500665902271 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"
12:38:22 INFO - 1500665902274 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"
12:38:22 INFO - 1500665902284 Marionette TRACE 394 <- [1,2,null,{}]
12:38:22 INFO - 1500665902285 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:38:22 INFO - 1500665902287 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"75531902-52e6-4288-9657-2de5b01c26dc","ELEMENT":"75531902-52e6-4288-9657-2de5b01c26dc"}}]
12:38:22 INFO - 1500665902288 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:38:22 INFO - 1500665902290 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"acd259f4-1430-4920-a426-d773ec196785","ELEMENT":"acd259f4-1430-4920-a426-d773ec196785"}}]
12:38:22 INFO - 1500665902292 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"75531902-52e6-4288-9657-2de5b01c26dc"}]
12:38:22 INFO - 1500665902526 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:38:22 INFO - 1500665902527 Marionette TRACE 394 <- [1,5,null,{}]
12:38:22 INFO - 1500665902528 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"acd259f4-1430-4920-a426-d773ec196785"}]
12:38:22 INFO - 1500665902542 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]
12:38:22 INFO - 1500665902544 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
12:38:22 INFO - 1500665902546 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:38:22 INFO - 1500665902548 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}]
12:38:22 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500665902550 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:38:22 INFO - 1500665902551 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:38:22 INFO - 1500665902552 Marionette TRACE 394 <- [1,9,null,{}]
12:38:22 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 350ms
12:38:22 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:38:22 INFO - 1500665902554 Marionette DEBUG Closed connection 394
12:38:22 INFO - 1500665902555 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49901
12:38:22 INFO - 1500665902555 Marionette DEBUG Closed connection 395
12:38:22 INFO - 1500665902556 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49902
12:38:22 INFO - 1500665902557 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:22 INFO - 1500665902558 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902559 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902562 Marionette TRACE 396 <- [1,1,null,{"sessionId":"bf71f61b-fad9-472a-ab17-26d328ca1f81","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902564 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:38:22 INFO - 1500665902564 Marionette TRACE 396 <- [1,2,null,{}]
12:38:22 INFO - 1500665902565 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:38:22 INFO - 1500665902566 Marionette TRACE 396 <- [1,3,null,{}]
12:38:22 INFO - 1500665902566 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:38:22 INFO - 1500665902567 Marionette TRACE 396 <- [1,4,null,{}]
12:38:22 INFO - 1500665902568 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}]
12:38:22 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500665902571 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:38:22 INFO - 1500665902571 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:38:22 INFO - 1500665902573 Marionette TRACE 396 <- [1,6,null,{}]
12:38:22 INFO - 1500665902574 Marionette DEBUG Closed connection 396
12:38:22 INFO - 1500665902575 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49903
12:38:22 INFO - 1500665902576 Marionette DEBUG Closed connection 397
12:38:22 INFO - 1500665902576 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49904
12:38:22 INFO - 1500665902577 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:38:22 INFO - 1500665902578 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902580 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902582 Marionette TRACE 398 <- [1,1,null,{"sessionId":"afa94043-93e9-4bf6-9eeb-aa019113744b","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902584 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:38:22 INFO - 1500665902585 Marionette TRACE 398 <- [1,2,null,{}]
12:38:22 INFO - 1500665902587 Marionette DEBUG Closed connection 398
12:38:22 INFO - 1500665902587 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49905
12:38:22 INFO - 1500665902588 Marionette DEBUG Closed connection 399
12:38:22 INFO - 1500665902589 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49906
12:38:22 INFO - 1500665902590 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:38:22 INFO - 1500665902590 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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}
12:38:22 INFO - 1500665902592 Marionette DEBUG Register listener.js for window 15
12:38:22 INFO - 1500665902594 Marionette TRACE 400 <- [1,1,null,{"sessionId":"5e207c45-2c76-4e1a-a71b-dee6f702db1c","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":3496,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsfjuy7.mozrunner","moz:accessibilityChecks":false}}]
12:38:22 INFO - 1500665902596 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"}]
12:38:22 INFO - 1500665902597 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"
12:38:22 INFO - 1500665902599 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"
12:38:22 INFO - 1500665902599 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"
12:38:22 INFO - 1500665902618 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"
12:38:22 INFO - 1500665902619 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"
12:38:22 INFO - 1500665902621 Marionette TRACE 400 <- [1,2,null,{}]
12:38:22 INFO - 1500665902629 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:38:22 INFO - 1500665902631 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7e563ff-21aa-461e-8d32-8cf8a2884de4","ELEMENT":"c7e563ff-21aa-461e-8d32-8cf8a2884de4"}}]
12:38:22 INFO - 1500665902632 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c7e563ff-21aa-461e-8d32-8cf8a2884de4"}]
12:38:22 INFO - 1500665902653 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:38:22 INFO - 1500665902654 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c7e563ff-21aa-461e-8d32-8cf8a2884de4"}]
12:38:22 INFO - 1500665902668 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element