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]
03:09:40 INFO - 1499854180533 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}]
03:09:40 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499854180536 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:09:40 INFO - 1499854180537 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:09:40 INFO - 1499854180538 Marionette TRACE 352 <- [1,6,null,{}]
03:09:40 INFO - 1499854180540 Marionette DEBUG Closed connection 352
03:09:40 INFO - 1499854180541 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49742
03:09:40 INFO - 1499854180541 Marionette DEBUG Closed connection 353
03:09:40 INFO - 1499854180542 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49743
03:09:40 INFO - 1499854180543 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:40 INFO - 1499854180544 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:40 INFO - 1499854180546 Marionette DEBUG Register listener.js for window 4294967297
03:09:40 INFO - 1499854180549 Marionette TRACE 354 <- [1,1,null,{"sessionId":"6616760b-f768-4b51-88c8-3e60b18b62f1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:40 INFO - 1499854180550 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:09:40 INFO - 1499854180550 Marionette TRACE 354 <- [1,2,null,{}]
03:09:40 INFO - 1499854180551 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:09:40 INFO - 1499854180551 Marionette TRACE 354 <- [1,3,null,{}]
03:09:40 INFO - 1499854180552 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:09:40 INFO - 1499854180553 Marionette TRACE 354 <- [1,4,null,{}]
03:09:40 INFO - 1499854180555 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}]
03:09:40 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499854180557 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:09:40 INFO - 1499854180558 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:09:40 INFO - 1499854180559 Marionette TRACE 354 <- [1,6,null,{}]
03:09:40 INFO - 1499854180560 Marionette DEBUG Closed connection 354
03:09:40 INFO - 1499854180561 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49744
03:09:40 INFO - 1499854180561 Marionette DEBUG Closed connection 355
03:09:40 INFO - 1499854180562 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49745
03:09:40 INFO - 1499854180563 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:40 INFO - 1499854180564 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:40 INFO - 1499854180566 Marionette DEBUG Register listener.js for window 4294967297
03:09:40 INFO - 1499854180569 Marionette TRACE 356 <- [1,1,null,{"sessionId":"10ba9a8d-9398-431f-92bd-545317c520eb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:40 INFO - 1499854180570 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:09:40 INFO - 1499854180571 Marionette TRACE 356 <- [1,2,null,{}]
03:09:40 INFO - 1499854180572 Marionette DEBUG Closed connection 356
03:09:40 INFO - 1499854180573 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49746
03:09:40 INFO - 1499854180573 Marionette DEBUG Closed connection 357
03:09:40 INFO - 1499854180574 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49747
03:09:40 INFO - 1499854180575 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:09:40 INFO - 1499854180575 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:40 INFO - 1499854180578 Marionette DEBUG Register listener.js for window 4294967297
03:09:40 INFO - 1499854180587 Marionette TRACE 358 <- [1,1,null,{"sessionId":"706d317b-2d0d-487a-861a-997338d806b5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:40 INFO - 1499854180588 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49302/clicks.html"}]
03:09:40 INFO - 1499854180590 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49302/hidden.html"
03:09:40 INFO - 1499854180597 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49302/hidden.html"
03:09:40 INFO - 1499854180598 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49302/hidden.html"
03:09:40 INFO - 1499854180603 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49302/clicks.html"
03:09:40 INFO - 1499854180607 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49302/clicks.html"
03:09:40 INFO - 1499854180612 Marionette TRACE 358 <- [1,2,null,{}]
03:09:40 INFO - 1499854180615 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:09:40 INFO - 1499854180617 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a18a11d2-edcd-43a4-953f-3daab9db4d2e","ELEMENT":"a18a11d2-edcd-43a4-953f-3daab9db4d2e"}}]
03:09:40 INFO - 1499854180618 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a18a11d2-edcd-43a4-953f-3daab9db4d2e"}]
03:09:40 INFO - 1499854180656 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49302/clicks.html"
03:09:40 INFO - 1499854180664 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49302/clicks.html"
03:09:40 INFO - 1499854180665 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49302/clicks.html"
03:09:40 INFO - 1499854180672 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49302/test.html"
03:09:40 INFO - 1499854180676 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49302/test.html"
03:09:40 INFO - 1499854180685 Marionette TRACE 358 <- [1,4,null,{}]
03:09:40 INFO - 1499854180691 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:09:40 INFO - 1499854180696 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9be5b67d-ab75-4097-b3ea-563f035c6df3","ELEMENT":"9be5b67d-ab75-4097-b3ea-563f035c6df3"}}]
03:09:40 INFO - 1499854180697 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:09:40 INFO - 1499854180698 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:09:40 INFO - 1499854180701 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}]
03:09:40 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 167ms
03:09:40 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499854180703 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:09:40 INFO - 1499854180704 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:09:40 INFO - 1499854180705 Marionette TRACE 358 <- [1,8,null,{}]
03:09:40 INFO - TEST-START | test_click.py TestClick.test_container_element
03:09:40 INFO - 1499854180707 Marionette DEBUG Closed connection 358
03:09:40 INFO - 1499854180707 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49750
03:09:40 INFO - 1499854180708 Marionette DEBUG Closed connection 359
03:09:40 INFO - 1499854180709 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49751
03:09:40 INFO - 1499854180710 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:40 INFO - 1499854180710 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:40 INFO - 1499854180713 Marionette DEBUG Register listener.js for window 4294967297
03:09:40 INFO - 1499854180716 Marionette TRACE 360 <- [1,1,null,{"sessionId":"976d0c74-e459-4935-8320-ff1ce198db8c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:40 INFO - 1499854180717 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:09:40 INFO - 1499854180717 Marionette TRACE 360 <- [1,2,null,{}]
03:09:40 INFO - 1499854180718 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:09:40 INFO - 1499854180718 Marionette TRACE 360 <- [1,3,null,{}]
03:09:40 INFO - 1499854180720 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:09:40 INFO - 1499854180720 Marionette TRACE 360 <- [1,4,null,{}]
03:09:40 INFO - 1499854180722 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}]
03:09:40 INFO - TEST-START: test_click.py TestClick.test_container_element1499854180726 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:09:40 INFO - 1499854180727 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:09:40 INFO - 1499854180728 Marionette TRACE 360 <- [1,6,null,{}]
03:09:40 INFO - 1499854180730 Marionette DEBUG Closed connection 360
03:09:40 INFO - 1499854180730 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49752
03:09:40 INFO - 1499854180731 Marionette DEBUG Closed connection 361
03:09:40 INFO - 1499854180732 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49753
03:09:40 INFO - 1499854180733 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:40 INFO - 1499854180733 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:40 INFO - 1499854180735 Marionette DEBUG Register listener.js for window 4294967297
03:09:40 INFO - 1499854180738 Marionette TRACE 362 <- [1,1,null,{"sessionId":"2442fc69-b02f-4894-9505-ae2153af93f0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:40 INFO - 1499854180739 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:09:40 INFO - 1499854180740 Marionette TRACE 362 <- [1,2,null,{}]
03:09:40 INFO - 1499854180742 Marionette DEBUG Closed connection 362
03:09:40 INFO - 1499854180742 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49754
03:09:40 INFO - 1499854180743 Marionette DEBUG Closed connection 363
03:09:40 INFO - 1499854180743 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49755
03:09:40 INFO - 1499854180744 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:09:40 INFO - 1499854180745 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:40 INFO - 1499854180747 Marionette DEBUG Register listener.js for window 4294967297
03:09:40 INFO - 1499854180750 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0b728ae8-a714-40b2-8eff-30588c0c363a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:40 INFO - 1499854180751 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"}]
03:09:40 INFO - 1499854180753 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49302/test.html"
03:09:40 INFO - 1499854180754 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49302/test.html"
03:09:40 INFO - 1499854180754 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49302/test.html"
03:09:40 INFO - 1499854180760 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"
03:09:40 INFO - 1499854180762 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"
03:09:40 INFO - 1499854180771 Marionette TRACE 364 <- [1,2,null,{}]
03:09:40 INFO - 1499854180778 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:09:40 INFO - 1499854180780 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"27a205b2-8e7a-408a-9732-a8b623f35192","ELEMENT":"27a205b2-8e7a-408a-9732-a8b623f35192"}}]
03:09:40 INFO - 1499854180781 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"27a205b2-8e7a-408a-9732-a8b623f35192"}]
03:09:41 INFO - 1499854181022 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:09:41 INFO - 1499854181023 Marionette TRACE 364 <- [1,4,null,{}]
03:09:41 INFO - 1499854181024 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"27a205b2-8e7a-408a-9732-a8b623f35192","name":"selected"}]
03:09:41 INFO - 1499854181028 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:09:41 INFO - 1499854181030 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}]
03:09:41 INFO - TEST-END: test_click.py TestClick.test_container_element1499854181032 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:09:41 INFO - 1499854181033 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:09:41 INFO - 1499854181035 Marionette TRACE 364 <- [1,7,null,{}]
03:09:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 329ms
03:09:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:09:41 INFO - 1499854181036 Marionette DEBUG Closed connection 364
03:09:41 INFO - 1499854181037 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49756
03:09:41 INFO - 1499854181038 Marionette DEBUG Closed connection 365
03:09:41 INFO - 1499854181038 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49757
03:09:41 INFO - 1499854181039 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:41 INFO - 1499854181040 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181043 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181046 Marionette TRACE 366 <- [1,1,null,{"sessionId":"c91a665e-dc44-4890-ad0f-082aaaebcf87","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181047 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:09:41 INFO - 1499854181048 Marionette TRACE 366 <- [1,2,null,{}]
03:09:41 INFO - 1499854181049 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:09:41 INFO - 1499854181049 Marionette TRACE 366 <- [1,3,null,{}]
03:09:41 INFO - 1499854181050 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:09:41 INFO - 1499854181050 Marionette TRACE 366 <- [1,4,null,{}]
03:09:41 INFO - 1499854181051 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}]
03:09:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499854181054 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:09:41 INFO - 1499854181055 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:09:41 INFO - 1499854181056 Marionette TRACE 366 <- [1,6,null,{}]
03:09:41 INFO - 1499854181058 Marionette DEBUG Closed connection 366
03:09:41 INFO - 1499854181058 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49758
03:09:41 INFO - 1499854181059 Marionette DEBUG Closed connection 367
03:09:41 INFO - 1499854181059 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49759
03:09:41 INFO - 1499854181060 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:41 INFO - 1499854181061 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181063 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181066 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b62308c4-427e-4c4f-b951-aca39e9ce2a0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181067 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:09:41 INFO - 1499854181068 Marionette TRACE 368 <- [1,2,null,{}]
03:09:41 INFO - 1499854181069 Marionette DEBUG Closed connection 368
03:09:41 INFO - 1499854181070 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49760
03:09:41 INFO - 1499854181071 Marionette DEBUG Closed connection 369
03:09:41 INFO - 1499854181071 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49761
03:09:41 INFO - 1499854181072 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:09:41 INFO - 1499854181073 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181075 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181078 Marionette TRACE 370 <- [1,1,null,{"sessionId":"1332f01b-e698-4778-af46-398c4582309c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181079 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"}]
03:09:41 INFO - 1499854181081 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"
03:09:41 INFO - 1499854181082 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"
03:09:41 INFO - 1499854181083 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"
03:09:41 INFO - 1499854181088 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"
03:09:41 INFO - 1499854181090 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"
03:09:41 INFO - 1499854181096 Marionette TRACE 370 <- [1,2,null,{}]
03:09:41 INFO - 1499854181099 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:09:41 INFO - 1499854181101 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dd831d1e-6ed8-4274-af87-2b61ef402f92","ELEMENT":"dd831d1e-6ed8-4274-af87-2b61ef402f92"}}]
03:09:41 INFO - 1499854181102 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"dd831d1e-6ed8-4274-af87-2b61ef402f92"}]
03:09:41 INFO - 1499854181341 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:09:41 INFO - 1499854181342 Marionette TRACE 370 <- [1,4,null,{}]
03:09:41 INFO - 1499854181343 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"dd831d1e-6ed8-4274-af87-2b61ef402f92","name":"selected"}]
03:09:41 INFO - 1499854181347 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:09:41 INFO - 1499854181348 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}]
03:09:41 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499854181350 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:09:41 INFO - 1499854181351 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:09:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 316ms
03:09:41 INFO - 1499854181352 Marionette TRACE 370 <- [1,7,null,{}]
03:09:41 INFO - 1499854181354 Marionette DEBUG Closed connection 370
03:09:41 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:09:41 INFO - 1499854181356 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49762
03:09:41 INFO - 1499854181357 Marionette DEBUG Closed connection 371
03:09:41 INFO - 1499854181357 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49763
03:09:41 INFO - 1499854181358 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:41 INFO - 1499854181358 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181360 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181363 Marionette TRACE 372 <- [1,1,null,{"sessionId":"d770607e-2bbe-43ae-b2bf-902aeaa9407b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181364 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:09:41 INFO - 1499854181365 Marionette TRACE 372 <- [1,2,null,{}]
03:09:41 INFO - 1499854181366 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:09:41 INFO - 1499854181366 Marionette TRACE 372 <- [1,3,null,{}]
03:09:41 INFO - 1499854181367 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:09:41 INFO - 1499854181367 Marionette TRACE 372 <- [1,4,null,{}]
03:09:41 INFO - 1499854181370 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}]
03:09:41 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499854181372 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:09:41 INFO - 1499854181373 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:09:41 INFO - 1499854181374 Marionette TRACE 372 <- [1,6,null,{}]
03:09:41 INFO - 1499854181376 Marionette DEBUG Closed connection 372
03:09:41 INFO - 1499854181376 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49764
03:09:41 INFO - 1499854181377 Marionette DEBUG Closed connection 373
03:09:41 INFO - 1499854181378 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49765
03:09:41 INFO - 1499854181379 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:41 INFO - 1499854181380 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181382 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181384 Marionette TRACE 374 <- [1,1,null,{"sessionId":"40768752-327e-4369-9564-dcc0a37da4e1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181385 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:09:41 INFO - 1499854181387 Marionette TRACE 374 <- [1,2,null,{}]
03:09:41 INFO - 1499854181388 Marionette DEBUG Closed connection 374
03:09:41 INFO - 1499854181389 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49766
03:09:41 INFO - 1499854181389 Marionette DEBUG Closed connection 375
03:09:41 INFO - 1499854181390 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49767
03:09:41 INFO - 1499854181391 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:09:41 INFO - 1499854181391 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181393 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181396 Marionette TRACE 376 <- [1,1,null,{"sessionId":"feb04b3f-4e84-4280-991a-10cca9b350ae","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181397 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"}]
03:09:41 INFO - 1499854181399 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"
03:09:41 INFO - 1499854181400 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"
03:09:41 INFO - 1499854181401 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"
03:09:41 INFO - 1499854181406 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"
03:09:41 INFO - 1499854181408 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"
03:09:41 INFO - 1499854181416 Marionette TRACE 376 <- [1,2,null,{}]
03:09:41 INFO - 1499854181419 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:09:41 INFO - 1499854181422 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c6274397-eb8f-4773-adc0-eb8084e847c1","ELEMENT":"c6274397-eb8f-4773-adc0-eb8084e847c1"}}]
03:09:41 INFO - 1499854181423 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"c6274397-eb8f-4773-adc0-eb8084e847c1"}]
03:09:41 INFO - 1499854181664 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:09:41 INFO - 1499854181665 Marionette TRACE 376 <- [1,4,null,{}]
03:09:41 INFO - 1499854181667 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}]
03:09:41 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499854181670 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:09:41 INFO - 1499854181671 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:09:41 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 318ms
03:09:41 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:09:41 INFO - 1499854181672 Marionette TRACE 376 <- [1,6,null,{}]
03:09:41 INFO - 1499854181674 Marionette DEBUG Closed connection 376
03:09:41 INFO - 1499854181674 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49768
03:09:41 INFO - 1499854181675 Marionette DEBUG Closed connection 377
03:09:41 INFO - 1499854181676 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49769
03:09:41 INFO - 1499854181677 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:41 INFO - 1499854181677 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181680 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181683 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5909cb69-6dd6-4df7-8c45-f657a50833b3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181684 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:09:41 INFO - 1499854181684 Marionette TRACE 378 <- [1,2,null,{}]
03:09:41 INFO - 1499854181685 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:09:41 INFO - 1499854181685 Marionette TRACE 378 <- [1,3,null,{}]
03:09:41 INFO - 1499854181686 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:09:41 INFO - 1499854181686 Marionette TRACE 378 <- [1,4,null,{}]
03:09:41 INFO - 1499854181688 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}]
03:09:41 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499854181691 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:09:41 INFO - 1499854181692 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:09:41 INFO - 1499854181693 Marionette TRACE 378 <- [1,6,null,{}]
03:09:41 INFO - 1499854181694 Marionette DEBUG Closed connection 378
03:09:41 INFO - 1499854181695 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49770
03:09:41 INFO - 1499854181696 Marionette DEBUG Closed connection 379
03:09:41 INFO - 1499854181696 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49771
03:09:41 INFO - 1499854181697 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:41 INFO - 1499854181698 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181700 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181703 Marionette TRACE 380 <- [1,1,null,{"sessionId":"4ba2925f-599d-49a6-b5ca-029476b0ed11","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181704 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:09:41 INFO - 1499854181705 Marionette TRACE 380 <- [1,2,null,{}]
03:09:41 INFO - 1499854181707 Marionette DEBUG Closed connection 380
03:09:41 INFO - 1499854181707 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49772
03:09:41 INFO - 1499854181708 Marionette DEBUG Closed connection 381
03:09:41 INFO - 1499854181708 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49773
03:09:41 INFO - 1499854181709 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:09:41 INFO - 1499854181710 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181712 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181715 Marionette TRACE 382 <- [1,1,null,{"sessionId":"e14a5ca4-5293-4ee6-a0cb-e74fbdb1a0db","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181716 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"}]
03:09:41 INFO - 1499854181718 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"
03:09:41 INFO - 1499854181720 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"
03:09:41 INFO - 1499854181720 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"
03:09:41 INFO - 1499854181727 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"
03:09:41 INFO - 1499854181728 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"
03:09:41 INFO - 1499854181733 Marionette TRACE 382 <- [1,2,null,{}]
03:09:41 INFO - 1499854181736 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:09:41 INFO - 1499854181738 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c48c3936-1c48-445c-860d-b9a4ed7a3f2e","ELEMENT":"c48c3936-1c48-445c-860d-b9a4ed7a3f2e"}}]
03:09:41 INFO - 1499854181739 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c48c3936-1c48-445c-860d-b9a4ed7a3f2e"}]
03:09:41 INFO - 1499854181976 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:09:41 INFO - 1499854181977 Marionette TRACE 382 <- [1,4,null,{}]
03:09:41 INFO - 1499854181979 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}]
03:09:41 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499854181981 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:09:41 INFO - 1499854181982 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:09:41 INFO - 1499854181983 Marionette TRACE 382 <- [1,6,null,{}]
03:09:41 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 310ms
03:09:41 INFO - TEST-START | test_click.py TestClick.test_input_file
03:09:41 INFO - 1499854181984 Marionette DEBUG Closed connection 382
03:09:41 INFO - 1499854181985 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49774
03:09:41 INFO - 1499854181986 Marionette DEBUG Closed connection 383
03:09:41 INFO - 1499854181986 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49775
03:09:41 INFO - 1499854181987 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:41 INFO - 1499854181987 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:41 INFO - 1499854181989 Marionette DEBUG Register listener.js for window 4294967297
03:09:41 INFO - 1499854181992 Marionette TRACE 384 <- [1,1,null,{"sessionId":"1760d606-a667-40e9-a0cf-21814da27edc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:41 INFO - 1499854181993 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:09:41 INFO - 1499854181994 Marionette TRACE 384 <- [1,2,null,{}]
03:09:41 INFO - 1499854181994 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:09:41 INFO - 1499854181995 Marionette TRACE 384 <- [1,3,null,{}]
03:09:41 INFO - 1499854181996 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:09:41 INFO - 1499854181996 Marionette TRACE 384 <- [1,4,null,{}]
03:09:42 INFO - 1499854181997 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}]
03:09:42 INFO - TEST-START: test_click.py TestClick.test_input_file1499854182000 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:09:42 INFO - 1499854182001 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:09:42 INFO - 1499854182002 Marionette TRACE 384 <- [1,6,null,{}]
03:09:42 INFO - 1499854182003 Marionette DEBUG Closed connection 384
03:09:42 INFO - 1499854182003 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49776
03:09:42 INFO - 1499854182004 Marionette DEBUG Closed connection 385
03:09:42 INFO - 1499854182005 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49777
03:09:42 INFO - 1499854182006 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:42 INFO - 1499854182013 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:42 INFO - 1499854182016 Marionette DEBUG Register listener.js for window 4294967297
03:09:42 INFO - 1499854182019 Marionette TRACE 386 <- [1,1,null,{"sessionId":"639c1fe5-89ca-48a5-ad97-24315b3e7089","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:42 INFO - 1499854182020 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:09:42 INFO - 1499854182021 Marionette TRACE 386 <- [1,2,null,{}]
03:09:42 INFO - 1499854182022 Marionette DEBUG Closed connection 386
03:09:42 INFO - 1499854182023 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49778
03:09:42 INFO - 1499854182024 Marionette DEBUG Closed connection 387
03:09:42 INFO - 1499854182024 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49779
03:09:42 INFO - 1499854182025 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:09:42 INFO - 1499854182026 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:42 INFO - 1499854182028 Marionette DEBUG Register listener.js for window 4294967297
03:09:42 INFO - 1499854182030 Marionette TRACE 388 <- [1,1,null,{"sessionId":"49c946bd-baaa-420a-8a2f-aba07985971e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:42 INFO - 1499854182031 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:09:42 INFO - 1499854182033 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"
03:09:42 INFO - 1499854182034 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"
03:09:42 INFO - 1499854182035 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"
03:09:42 INFO - 1499854182040 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:09:42 INFO - 1499854182044 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:09:42 INFO - 1499854182047 Marionette TRACE 388 <- [1,2,null,{}]
03:09:42 INFO - 1499854182050 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:09:42 INFO - 1499854182052 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6227c07d-c0ec-4dcb-aa2a-a1f18805b742","ELEMENT":"6227c07d-c0ec-4dcb-aa2a-a1f18805b742"}}]
03:09:42 INFO - 1499854182053 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6227c07d-c0ec-4dcb-aa2a-a1f18805b742"}]
03:09:42 INFO - 1499854182083 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]
03:09:42 INFO - 1499854182085 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}]
03:09:42 INFO - TEST-END: test_click.py TestClick.test_input_file1499854182087 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:09:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 106ms
03:09:42 INFO - 1499854182088 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:09:42 INFO - 1499854182089 Marionette TRACE 388 <- [1,6,null,{}]
03:09:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:09:42 INFO - 1499854182091 Marionette DEBUG Closed connection 388
03:09:42 INFO - 1499854182092 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49780
03:09:42 INFO - 1499854182093 Marionette DEBUG Closed connection 389
03:09:42 INFO - 1499854182093 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49781
03:09:42 INFO - 1499854182094 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:42 INFO - 1499854182095 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:42 INFO - 1499854182097 Marionette DEBUG Register listener.js for window 4294967297
03:09:42 INFO - 1499854182099 Marionette TRACE 390 <- [1,1,null,{"sessionId":"b160e1fd-4fda-4994-abb5-62b6fe5a627d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:42 INFO - 1499854182101 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:09:42 INFO - 1499854182101 Marionette TRACE 390 <- [1,2,null,{}]
03:09:42 INFO - 1499854182102 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:09:42 INFO - 1499854182102 Marionette TRACE 390 <- [1,3,null,{}]
03:09:42 INFO - 1499854182103 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:09:42 INFO - 1499854182103 Marionette TRACE 390 <- [1,4,null,{}]
03:09:42 INFO - 1499854182105 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}]
03:09:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499854182107 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:09:42 INFO - 1499854182108 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:09:42 INFO - 1499854182109 Marionette TRACE 390 <- [1,6,null,{}]
03:09:42 INFO - 1499854182111 Marionette DEBUG Closed connection 390
03:09:42 INFO - 1499854182111 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49782
03:09:42 INFO - 1499854182112 Marionette DEBUG Closed connection 391
03:09:42 INFO - 1499854182112 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49783
03:09:42 INFO - 1499854182113 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:42 INFO - 1499854182114 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:42 INFO - 1499854182116 Marionette DEBUG Register listener.js for window 4294967297
03:09:42 INFO - 1499854182118 Marionette TRACE 392 <- [1,1,null,{"sessionId":"9e66bb42-66c9-473c-94d9-088c4fe57953","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:42 INFO - 1499854182119 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:09:42 INFO - 1499854182121 Marionette TRACE 392 <- [1,2,null,{}]
03:09:42 INFO - 1499854182122 Marionette DEBUG Closed connection 392
03:09:42 INFO - 1499854182122 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49784
03:09:42 INFO - 1499854182123 Marionette DEBUG Closed connection 393
03:09:42 INFO - 1499854182123 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49785
03:09:42 INFO - 1499854182125 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:09:42 INFO - 1499854182125 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:42 INFO - 1499854182127 Marionette DEBUG Register listener.js for window 4294967297
03:09:42 INFO - 1499854182130 Marionette TRACE 394 <- [1,1,null,{"sessionId":"8ce5e887-ec6d-41fe-ae90-b68606a827c8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:42 INFO - 1499854182131 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"}]
03:09:42 INFO - 1499854182132 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:09:42 INFO - 1499854182134 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:09:42 INFO - 1499854182134 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:09:42 INFO - 1499854182140 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"
03:09:42 INFO - 1499854182142 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"
03:09:42 INFO - 1499854182147 Marionette TRACE 394 <- [1,2,null,{}]
03:09:42 INFO - 1499854182150 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:09:42 INFO - 1499854182152 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8a157f6-b4ba-43b2-8428-6db597adfa9d","ELEMENT":"e8a157f6-b4ba-43b2-8428-6db597adfa9d"}}]
03:09:42 INFO - 1499854182153 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:09:42 INFO - 1499854182155 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"06417a9b-cd43-4246-a110-2822f37491b3","ELEMENT":"06417a9b-cd43-4246-a110-2822f37491b3"}}]
03:09:42 INFO - 1499854182155 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e8a157f6-b4ba-43b2-8428-6db597adfa9d"}]
03:09:42 INFO - 1499854182388 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:09:42 INFO - 1499854182389 Marionette TRACE 394 <- [1,5,null,{}]
03:09:42 INFO - 1499854182391 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"06417a9b-cd43-4246-a110-2822f37491b3"}]
03:09:42 INFO - 1499854182404 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]
03:09:42 INFO - 1499854182405 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:09:42 INFO - 1499854182409 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:09:42 INFO - 1499854182410 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}]
03:09:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499854182413 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:09:42 INFO - 1499854182414 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:09:42 INFO - 1499854182415 Marionette TRACE 394 <- [1,9,null,{}]
03:09:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 325ms
03:09:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:09:42 INFO - 1499854182416 Marionette DEBUG Closed connection 394
03:09:42 INFO - 1499854182418 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49786
03:09:42 INFO - 1499854182419 Marionette DEBUG Closed connection 395
03:09:42 INFO - 1499854182419 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49787
03:09:42 INFO - 1499854182420 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:42 INFO - 1499854182421 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:42 INFO - 1499854182423 Marionette DEBUG Register listener.js for window 4294967297
03:09:42 INFO - 1499854182426 Marionette TRACE 396 <- [1,1,null,{"sessionId":"41ecab10-d2b0-4952-8adf-e96ced096ff2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:42 INFO - 1499854182427 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:09:42 INFO - 1499854182427 Marionette TRACE 396 <- [1,2,null,{}]
03:09:42 INFO - 1499854182428 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:09:42 INFO - 1499854182429 Marionette TRACE 396 <- [1,3,null,{}]
03:09:42 INFO - 1499854182429 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:09:42 INFO - 1499854182430 Marionette TRACE 396 <- [1,4,null,{}]
03:09:42 INFO - 1499854182431 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}]
03:09:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499854182434 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:09:42 INFO - 1499854182434 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:09:42 INFO - 1499854182436 Marionette TRACE 396 <- [1,6,null,{}]
03:09:42 INFO - 1499854182437 Marionette DEBUG Closed connection 396
03:09:42 INFO - 1499854182437 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49788
03:09:42 INFO - 1499854182438 Marionette DEBUG Closed connection 397
03:09:42 INFO - 1499854182438 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49789
03:09:42 INFO - 1499854182439 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:09:42 INFO - 1499854182440 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:42 INFO - 1499854182442 Marionette DEBUG Register listener.js for window 4294967297
03:09:42 INFO - 1499854182445 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b168fa2d-3a3c-4bcd-852b-948c29025930","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:42 INFO - 1499854182445 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:09:42 INFO - 1499854182447 Marionette TRACE 398 <- [1,2,null,{}]
03:09:42 INFO - 1499854182448 Marionette DEBUG Closed connection 398
03:09:42 INFO - 1499854182448 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49790
03:09:42 INFO - 1499854182449 Marionette DEBUG Closed connection 399
03:09:42 INFO - 1499854182450 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49791
03:09:42 INFO - 1499854182451 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:09:42 INFO - 1499854182451 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}
03:09:42 INFO - 1499854182453 Marionette DEBUG Register listener.js for window 4294967297
03:09:42 INFO - 1499854182456 Marionette TRACE 400 <- [1,1,null,{"sessionId":"133372d3-d7cf-4bd1-b61f-2e8939076964","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3932,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-155\\AppData\\Local\\Temp\\tmpyq_rj4.mozrunner","moz:accessibilityChecks":false}}]
03:09:42 INFO - 1499854182458 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"}]
03:09:42 INFO - 1499854182459 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"
03:09:42 INFO - 1499854182461 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"
03:09:42 INFO - 1499854182462 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"
03:09:42 INFO - 1499854182467 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"
03:09:42 INFO - 1499854182469 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"
03:09:42 INFO - 1499854182472 Marionette TRACE 400 <- [1,2,null,{}]
03:09:42 INFO - 1499854182479 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:09:42 INFO - 1499854182482 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0109ec23-8cd2-4a9e-9492-c0def9ef3de3","ELEMENT":"0109ec23-8cd2-4a9e-9492-c0def9ef3de3"}}]
03:09:42 INFO - 1499854182483 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"0109ec23-8cd2-4a9e-9492-c0def9ef3de3"}]
03:09:42 INFO - 1499854182505 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:09:42 INFO - 1499854182506 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"0109ec23-8cd2-4a9e-9492-c0def9ef3de3"}]
03:09:42 INFO - 1499854182520 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element