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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:56:19 INFO - 1499446579590 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}]
09:56:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499446579592 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:56:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 123ms
09:56:19 INFO - 1499446579593 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:56:19 INFO - 1499446579594 Marionette TRACE 352 <- [1,6,null,{}]
09:56:19 INFO - 1499446579596 Marionette DEBUG Closed connection 352
09:56:19 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:56:19 INFO - 1499446579598 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49752
09:56:19 INFO - 1499446579598 Marionette DEBUG Closed connection 353
09:56:19 INFO - 1499446579599 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49753
09:56:19 INFO - 1499446579600 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:19 INFO - 1499446579600 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:19 INFO - 1499446579602 Marionette DEBUG Register listener.js for window 12
09:56:19 INFO - 1499446579605 Marionette TRACE 354 <- [1,1,null,{"sessionId":"a22e857f-2da2-4b71-9288-e640460fd7e6","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:19 INFO - 1499446579607 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:56:19 INFO - 1499446579607 Marionette TRACE 354 <- [1,2,null,{}]
09:56:19 INFO - 1499446579608 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:19 INFO - 1499446579608 Marionette TRACE 354 <- [1,3,null,{}]
09:56:19 INFO - 1499446579609 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:19 INFO - 1499446579609 Marionette TRACE 354 <- [1,4,null,{}]
09:56:19 INFO - 1499446579611 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}]
09:56:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499446579613 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:56:19 INFO - 1499446579614 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:56:19 INFO - 1499446579615 Marionette TRACE 354 <- [1,6,null,{}]
09:56:19 INFO - 1499446579617 Marionette DEBUG Closed connection 354
09:56:19 INFO - 1499446579617 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49754
09:56:19 INFO - 1499446579618 Marionette DEBUG Closed connection 355
09:56:19 INFO - 1499446579619 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49755
09:56:19 INFO - 1499446579620 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:19 INFO - 1499446579620 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:19 INFO - 1499446579622 Marionette DEBUG Register listener.js for window 12
09:56:19 INFO - 1499446579625 Marionette TRACE 356 <- [1,1,null,{"sessionId":"fe70a601-de17-4251-a956-40bd29605f0e","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:19 INFO - 1499446579626 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:56:19 INFO - 1499446579627 Marionette TRACE 356 <- [1,2,null,{}]
09:56:19 INFO - 1499446579629 Marionette DEBUG Closed connection 356
09:56:19 INFO - 1499446579630 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49756
09:56:19 INFO - 1499446579630 Marionette DEBUG Closed connection 357
09:56:19 INFO - 1499446579631 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49757
09:56:19 INFO - 1499446579632 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:19 INFO - 1499446579632 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:19 INFO - 1499446579635 Marionette DEBUG Register listener.js for window 12
09:56:19 INFO - 1499446579638 Marionette TRACE 358 <- [1,1,null,{"sessionId":"40f71ef6-bfb1-4e8f-b475-8b178f932d0c","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:19 INFO - 1499446579640 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49331/clicks.html"}]
09:56:19 INFO - 1499446579641 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/hidden.html"
09:56:19 INFO - 1499446579646 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/hidden.html"
09:56:19 INFO - 1499446579646 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/hidden.html"
09:56:19 INFO - 1499446579665 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/clicks.html"
09:56:19 INFO - 1499446579666 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/clicks.html"
09:56:19 INFO - 1499446579668 Marionette TRACE 358 <- [1,2,null,{}]
09:56:19 INFO - 1499446579669 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:56:19 INFO - 1499446579671 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84904283-8517-4220-a0c0-9060852298ad","ELEMENT":"84904283-8517-4220-a0c0-9060852298ad"}}]
09:56:19 INFO - 1499446579678 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"84904283-8517-4220-a0c0-9060852298ad"}]
09:56:19 INFO - 1499446579716 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/clicks.html"
09:56:19 INFO - 1499446579720 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/clicks.html"
09:56:19 INFO - 1499446579721 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/clicks.html"
09:56:19 INFO - 1499446579763 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/test.html"
09:56:19 INFO - 1499446579764 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/test.html"
09:56:19 INFO - 1499446579766 Marionette TRACE 358 <- [1,4,null,{}]
09:56:19 INFO - 1499446579768 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:56:19 INFO - 1499446579771 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aeb7eb8f-da3b-4aaa-811f-1d820fec27ff","ELEMENT":"aeb7eb8f-da3b-4aaa-811f-1d820fec27ff"}}]
09:56:19 INFO - 1499446579772 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:56:19 INFO - 1499446579772 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:56:19 INFO - 1499446579779 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}]
09:56:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499446579781 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:56:19 INFO - 1499446579781 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:56:19 INFO - 1499446579782 Marionette TRACE 358 <- [1,8,null,{}]
09:56:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 187ms
09:56:19 INFO - TEST-START | test_click.py TestClick.test_container_element
09:56:19 INFO - 1499446579784 Marionette DEBUG Closed connection 358
09:56:19 INFO - 1499446579785 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49760
09:56:19 INFO - 1499446579785 Marionette DEBUG Closed connection 359
09:56:19 INFO - 1499446579786 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49761
09:56:19 INFO - 1499446579787 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:19 INFO - 1499446579787 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:19 INFO - 1499446579790 Marionette DEBUG Register listener.js for window 12
09:56:19 INFO - 1499446579793 Marionette TRACE 360 <- [1,1,null,{"sessionId":"32340e00-a757-422e-ad84-f2d1e9bddb5a","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:19 INFO - 1499446579795 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:56:19 INFO - 1499446579795 Marionette TRACE 360 <- [1,2,null,{}]
09:56:19 INFO - 1499446579796 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:19 INFO - 1499446579797 Marionette TRACE 360 <- [1,3,null,{}]
09:56:19 INFO - 1499446579797 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:19 INFO - 1499446579798 Marionette TRACE 360 <- [1,4,null,{}]
09:56:19 INFO - 1499446579799 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}]
09:56:19 INFO - TEST-START: test_click.py TestClick.test_container_element1499446579801 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:56:19 INFO - 1499446579802 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:56:19 INFO - 1499446579803 Marionette TRACE 360 <- [1,6,null,{}]
09:56:19 INFO - 1499446579805 Marionette DEBUG Closed connection 360
09:56:19 INFO - 1499446579806 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49762
09:56:19 INFO - 1499446579807 Marionette DEBUG Closed connection 361
09:56:19 INFO - 1499446579808 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49763
09:56:19 INFO - 1499446579809 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:19 INFO - 1499446579809 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:19 INFO - 1499446579811 Marionette DEBUG Register listener.js for window 12
09:56:19 INFO - 1499446579814 Marionette TRACE 362 <- [1,1,null,{"sessionId":"56c77b75-57b7-4ae4-b55c-ccddf369e6a9","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:19 INFO - 1499446579816 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:56:19 INFO - 1499446579817 Marionette TRACE 362 <- [1,2,null,{}]
09:56:19 INFO - 1499446579819 Marionette DEBUG Closed connection 362
09:56:19 INFO - 1499446579820 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49764
09:56:19 INFO - 1499446579820 Marionette DEBUG Closed connection 363
09:56:19 INFO - 1499446579821 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49765
09:56:19 INFO - 1499446579822 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:19 INFO - 1499446579823 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:19 INFO - 1499446579825 Marionette DEBUG Register listener.js for window 12
09:56:19 INFO - 1499446579828 Marionette TRACE 364 <- [1,1,null,{"sessionId":"67553048-c1df-4e2e-9fd0-be25d35d2564","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:19 INFO - 1499446579830 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"}]
09:56:19 INFO - 1499446579831 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/test.html"
09:56:19 INFO - 1499446579833 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/test.html"
09:56:19 INFO - 1499446579833 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/test.html"
09:56:19 INFO - 1499446579852 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"
09:56:19 INFO - 1499446579856 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"
09:56:19 INFO - 1499446579864 Marionette TRACE 364 <- [1,2,null,{}]
09:56:19 INFO - 1499446579865 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:56:19 INFO - 1499446579867 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"db6f5aca-56fd-4c93-8e00-eafd1ce2bad0","ELEMENT":"db6f5aca-56fd-4c93-8e00-eafd1ce2bad0"}}]
09:56:19 INFO - 1499446579868 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"db6f5aca-56fd-4c93-8e00-eafd1ce2bad0"}]
09:56:20 INFO - 1499446580103 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:20 INFO - 1499446580104 Marionette TRACE 364 <- [1,4,null,{}]
09:56:20 INFO - 1499446580105 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"db6f5aca-56fd-4c93-8e00-eafd1ce2bad0","name":"selected"}]
09:56:20 INFO - 1499446580108 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:56:20 INFO - 1499446580110 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}]
09:56:20 INFO - TEST-END: test_click.py TestClick.test_container_element1499446580112 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:56:20 INFO - 1499446580112 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:56:20 INFO - 1499446580114 Marionette TRACE 364 <- [1,7,null,{}]
09:56:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 331ms
09:56:20 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:56:20 INFO - 1499446580115 Marionette DEBUG Closed connection 364
09:56:20 INFO - 1499446580116 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49766
09:56:20 INFO - 1499446580117 Marionette DEBUG Closed connection 365
09:56:20 INFO - 1499446580117 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49767
09:56:20 INFO - 1499446580118 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:20 INFO - 1499446580118 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580120 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580123 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fd794434-1356-4016-963e-1d10a3e4a1b7","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580124 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:56:20 INFO - 1499446580125 Marionette TRACE 366 <- [1,2,null,{}]
09:56:20 INFO - 1499446580126 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:20 INFO - 1499446580126 Marionette TRACE 366 <- [1,3,null,{}]
09:56:20 INFO - 1499446580127 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:20 INFO - 1499446580127 Marionette TRACE 366 <- [1,4,null,{}]
09:56:20 INFO - 1499446580128 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}]
09:56:20 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499446580131 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:56:20 INFO - 1499446580132 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:56:20 INFO - 1499446580133 Marionette TRACE 366 <- [1,6,null,{}]
09:56:20 INFO - 1499446580134 Marionette DEBUG Closed connection 366
09:56:20 INFO - 1499446580135 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49768
09:56:20 INFO - 1499446580135 Marionette DEBUG Closed connection 367
09:56:20 INFO - 1499446580136 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49769
09:56:20 INFO - 1499446580137 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:20 INFO - 1499446580137 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580139 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580141 Marionette TRACE 368 <- [1,1,null,{"sessionId":"a5ef2afd-a7d8-4b2d-b179-992def4de46e","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580143 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:56:20 INFO - 1499446580144 Marionette TRACE 368 <- [1,2,null,{}]
09:56:20 INFO - 1499446580146 Marionette DEBUG Closed connection 368
09:56:20 INFO - 1499446580146 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49770
09:56:20 INFO - 1499446580147 Marionette DEBUG Closed connection 369
09:56:20 INFO - 1499446580148 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49771
09:56:20 INFO - 1499446580148 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:20 INFO - 1499446580149 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580151 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580153 Marionette TRACE 370 <- [1,1,null,{"sessionId":"f1b97b83-0af0-4f9c-b410-d409e161032b","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580155 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"}]
09:56:20 INFO - 1499446580156 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"
09:56:20 INFO - 1499446580158 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"
09:56:20 INFO - 1499446580158 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"
09:56:20 INFO - 1499446580167 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"
09:56:20 INFO - 1499446580171 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"
09:56:20 INFO - 1499446580181 Marionette TRACE 370 <- [1,2,null,{}]
09:56:20 INFO - 1499446580182 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:56:20 INFO - 1499446580184 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"23fa00ca-5fff-4931-9a96-a10566a5b186","ELEMENT":"23fa00ca-5fff-4931-9a96-a10566a5b186"}}]
09:56:20 INFO - 1499446580185 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"23fa00ca-5fff-4931-9a96-a10566a5b186"}]
09:56:20 INFO - 1499446580428 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:20 INFO - 1499446580428 Marionette TRACE 370 <- [1,4,null,{}]
09:56:20 INFO - 1499446580429 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"23fa00ca-5fff-4931-9a96-a10566a5b186","name":"selected"}]
09:56:20 INFO - 1499446580433 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:56:20 INFO - 1499446580435 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}]
09:56:20 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499446580437 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:56:20 INFO - 1499446580438 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:56:20 INFO - 1499446580439 Marionette TRACE 370 <- [1,7,null,{}]
09:56:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 326ms
09:56:20 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:56:20 INFO - 1499446580442 Marionette DEBUG Closed connection 370
09:56:20 INFO - 1499446580443 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49772
09:56:20 INFO - 1499446580444 Marionette DEBUG Closed connection 371
09:56:20 INFO - 1499446580444 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49773
09:56:20 INFO - 1499446580445 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:20 INFO - 1499446580445 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580448 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580450 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9daa7965-4d20-4398-91b2-d9cfbdbbe110","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580452 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:56:20 INFO - 1499446580452 Marionette TRACE 372 <- [1,2,null,{}]
09:56:20 INFO - 1499446580453 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:20 INFO - 1499446580454 Marionette TRACE 372 <- [1,3,null,{}]
09:56:20 INFO - 1499446580455 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:20 INFO - 1499446580455 Marionette TRACE 372 <- [1,4,null,{}]
09:56:20 INFO - 1499446580456 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}]
09:56:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499446580459 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:56:20 INFO - 1499446580460 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:56:20 INFO - 1499446580461 Marionette TRACE 372 <- [1,6,null,{}]
09:56:20 INFO - 1499446580462 Marionette DEBUG Closed connection 372
09:56:20 INFO - 1499446580463 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49774
09:56:20 INFO - 1499446580464 Marionette DEBUG Closed connection 373
09:56:20 INFO - 1499446580464 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49775
09:56:20 INFO - 1499446580465 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:20 INFO - 1499446580466 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580468 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580470 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e0ff3299-68ed-414a-9b0a-398b1966e171","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580472 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:56:20 INFO - 1499446580473 Marionette TRACE 374 <- [1,2,null,{}]
09:56:20 INFO - 1499446580475 Marionette DEBUG Closed connection 374
09:56:20 INFO - 1499446580476 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49776
09:56:20 INFO - 1499446580476 Marionette DEBUG Closed connection 375
09:56:20 INFO - 1499446580477 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49777
09:56:20 INFO - 1499446580478 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:20 INFO - 1499446580478 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580480 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580483 Marionette TRACE 376 <- [1,1,null,{"sessionId":"459f8287-1379-476c-9b98-b58428b505fa","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580485 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"}]
09:56:20 INFO - 1499446580486 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"
09:56:20 INFO - 1499446580488 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"
09:56:20 INFO - 1499446580488 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"
09:56:20 INFO - 1499446580510 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"
09:56:20 INFO - 1499446580512 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"
09:56:20 INFO - 1499446580515 Marionette TRACE 376 <- [1,2,null,{}]
09:56:20 INFO - 1499446580517 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:56:20 INFO - 1499446580519 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"855f862f-f1b9-4d65-be37-efeb3aba1020","ELEMENT":"855f862f-f1b9-4d65-be37-efeb3aba1020"}}]
09:56:20 INFO - 1499446580520 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"855f862f-f1b9-4d65-be37-efeb3aba1020"}]
09:56:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
09:56:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:56:20 INFO - 1499446580757 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:20 INFO - 1499446580757 Marionette TRACE 376 <- [1,4,null,{}]
09:56:20 INFO - 1499446580759 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}]
09:56:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499446580761 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:56:20 INFO - 1499446580762 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:56:20 INFO - 1499446580763 Marionette TRACE 376 <- [1,6,null,{}]
09:56:20 INFO - 1499446580765 Marionette DEBUG Closed connection 376
09:56:20 INFO - 1499446580765 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49778
09:56:20 INFO - 1499446580766 Marionette DEBUG Closed connection 377
09:56:20 INFO - 1499446580766 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49779
09:56:20 INFO - 1499446580767 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:20 INFO - 1499446580768 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580770 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580779 Marionette TRACE 378 <- [1,1,null,{"sessionId":"dabd6cfc-d1a9-439e-958a-9c05b71e49e5","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580781 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:56:20 INFO - 1499446580781 Marionette TRACE 378 <- [1,2,null,{}]
09:56:20 INFO - 1499446580783 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:20 INFO - 1499446580783 Marionette TRACE 378 <- [1,3,null,{}]
09:56:20 INFO - 1499446580784 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:20 INFO - 1499446580784 Marionette TRACE 378 <- [1,4,null,{}]
09:56:20 INFO - 1499446580786 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}]
09:56:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499446580788 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:56:20 INFO - 1499446580789 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:56:20 INFO - 1499446580790 Marionette TRACE 378 <- [1,6,null,{}]
09:56:20 INFO - 1499446580791 Marionette DEBUG Closed connection 378
09:56:20 INFO - 1499446580792 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49780
09:56:20 INFO - 1499446580793 Marionette DEBUG Closed connection 379
09:56:20 INFO - 1499446580793 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49781
09:56:20 INFO - 1499446580794 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:20 INFO - 1499446580795 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580796 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580799 Marionette TRACE 380 <- [1,1,null,{"sessionId":"2bbb4d17-350e-4dd5-940a-dbd37b3c51ea","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580800 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:56:20 INFO - 1499446580801 Marionette TRACE 380 <- [1,2,null,{}]
09:56:20 INFO - 1499446580803 Marionette DEBUG Closed connection 380
09:56:20 INFO - 1499446580804 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49782
09:56:20 INFO - 1499446580804 Marionette DEBUG Closed connection 381
09:56:20 INFO - 1499446580805 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49783
09:56:20 INFO - 1499446580806 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:20 INFO - 1499446580807 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:20 INFO - 1499446580808 Marionette DEBUG Register listener.js for window 12
09:56:20 INFO - 1499446580811 Marionette TRACE 382 <- [1,1,null,{"sessionId":"93e1c290-8074-45ff-a88d-605c5c2603ce","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:20 INFO - 1499446580813 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"}]
09:56:20 INFO - 1499446580814 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"
09:56:20 INFO - 1499446580816 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"
09:56:20 INFO - 1499446580816 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"
09:56:20 INFO - 1499446580832 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"
09:56:20 INFO - 1499446580835 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"
09:56:20 INFO - 1499446580837 Marionette TRACE 382 <- [1,2,null,{}]
09:56:20 INFO - 1499446580838 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:56:20 INFO - 1499446580846 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ade586b4-fdd4-4c26-b57e-e83dabe7a486","ELEMENT":"ade586b4-fdd4-4c26-b57e-e83dabe7a486"}}]
09:56:20 INFO - 1499446580847 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ade586b4-fdd4-4c26-b57e-e83dabe7a486"}]
09:56:21 INFO - 1499446581086 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:21 INFO - 1499446581086 Marionette TRACE 382 <- [1,4,null,{}]
09:56:21 INFO - 1499446581088 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}]
09:56:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499446581090 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:56:21 INFO - 1499446581091 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:56:21 INFO - 1499446581092 Marionette TRACE 382 <- [1,6,null,{}]
09:56:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 329ms
09:56:21 INFO - TEST-START | test_click.py TestClick.test_input_file
09:56:21 INFO - 1499446581094 Marionette DEBUG Closed connection 382
09:56:21 INFO - 1499446581095 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49784
09:56:21 INFO - 1499446581095 Marionette DEBUG Closed connection 383
09:56:21 INFO - 1499446581096 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49785
09:56:21 INFO - 1499446581097 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:21 INFO - 1499446581097 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581099 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581101 Marionette TRACE 384 <- [1,1,null,{"sessionId":"6306339c-1787-417a-87f3-719b98630be2","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581103 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:56:21 INFO - 1499446581104 Marionette TRACE 384 <- [1,2,null,{}]
09:56:21 INFO - 1499446581105 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:21 INFO - 1499446581105 Marionette TRACE 384 <- [1,3,null,{}]
09:56:21 INFO - 1499446581106 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:21 INFO - 1499446581106 Marionette TRACE 384 <- [1,4,null,{}]
09:56:21 INFO - 1499446581108 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}]
09:56:21 INFO - TEST-START: test_click.py TestClick.test_input_file1499446581110 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:56:21 INFO - 1499446581110 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:56:21 INFO - 1499446581111 Marionette TRACE 384 <- [1,6,null,{}]
09:56:21 INFO - 1499446581113 Marionette DEBUG Closed connection 384
09:56:21 INFO - 1499446581114 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49786
09:56:21 INFO - 1499446581114 Marionette DEBUG Closed connection 385
09:56:21 INFO - 1499446581115 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49787
09:56:21 INFO - 1499446581116 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:21 INFO - 1499446581116 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581118 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581120 Marionette TRACE 386 <- [1,1,null,{"sessionId":"418d99de-d4c6-41ba-8214-5c78f0bf0c9a","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581123 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:56:21 INFO - 1499446581124 Marionette TRACE 386 <- [1,2,null,{}]
09:56:21 INFO - 1499446581126 Marionette DEBUG Closed connection 386
09:56:21 INFO - 1499446581126 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49788
09:56:21 INFO - 1499446581127 Marionette DEBUG Closed connection 387
09:56:21 INFO - 1499446581128 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49789
09:56:21 INFO - 1499446581128 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:21 INFO - 1499446581129 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581131 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581134 Marionette TRACE 388 <- [1,1,null,{"sessionId":"fe50f44e-4448-46a8-a012-58a78c68769b","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581135 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:56:21 INFO - 1499446581136 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"
09:56:21 INFO - 1499446581138 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"
09:56:21 INFO - 1499446581139 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"
09:56:21 INFO - 1499446581148 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:21 INFO - 1499446581151 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:21 INFO - 1499446581153 Marionette TRACE 388 <- [1,2,null,{}]
09:56:21 INFO - 1499446581162 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:56:21 INFO - 1499446581164 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6071fe09-da17-42b1-8ab3-022d51043222","ELEMENT":"6071fe09-da17-42b1-8ab3-022d51043222"}}]
09:56:21 INFO - 1499446581165 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6071fe09-da17-42b1-8ab3-022d51043222"}]
09:56:21 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 112ms
09:56:21 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:56:21 INFO - 1499446581197 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:56:21 INFO - 1499446581200 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}]
09:56:21 INFO - TEST-END: test_click.py TestClick.test_input_file1499446581202 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:56:21 INFO - 1499446581203 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:56:21 INFO - 1499446581204 Marionette TRACE 388 <- [1,6,null,{}]
09:56:21 INFO - 1499446581222 Marionette DEBUG Closed connection 388
09:56:21 INFO - 1499446581222 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49790
09:56:21 INFO - 1499446581223 Marionette DEBUG Closed connection 389
09:56:21 INFO - 1499446581224 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49791
09:56:21 INFO - 1499446581225 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:21 INFO - 1499446581225 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581227 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581229 Marionette TRACE 390 <- [1,1,null,{"sessionId":"dc4b3a2e-25ef-495c-93c9-bc007c084779","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581231 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:56:21 INFO - 1499446581231 Marionette TRACE 390 <- [1,2,null,{}]
09:56:21 INFO - 1499446581232 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:21 INFO - 1499446581233 Marionette TRACE 390 <- [1,3,null,{}]
09:56:21 INFO - 1499446581234 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:21 INFO - 1499446581234 Marionette TRACE 390 <- [1,4,null,{}]
09:56:21 INFO - 1499446581235 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}]
09:56:21 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499446581237 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:56:21 INFO - 1499446581238 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:56:21 INFO - 1499446581239 Marionette TRACE 390 <- [1,6,null,{}]
09:56:21 INFO - 1499446581241 Marionette DEBUG Closed connection 390
09:56:21 INFO - 1499446581241 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49792
09:56:21 INFO - 1499446581242 Marionette DEBUG Closed connection 391
09:56:21 INFO - 1499446581243 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49793
09:56:21 INFO - 1499446581243 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:21 INFO - 1499446581244 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581246 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581248 Marionette TRACE 392 <- [1,1,null,{"sessionId":"6f64a426-7c0e-4eca-8e9a-0822a526f04b","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581250 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:56:21 INFO - 1499446581251 Marionette TRACE 392 <- [1,2,null,{}]
09:56:21 INFO - 1499446581252 Marionette DEBUG Closed connection 392
09:56:21 INFO - 1499446581253 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49794
09:56:21 INFO - 1499446581254 Marionette DEBUG Closed connection 393
09:56:21 INFO - 1499446581254 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49795
09:56:21 INFO - 1499446581255 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:21 INFO - 1499446581256 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581258 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581260 Marionette TRACE 394 <- [1,1,null,{"sessionId":"df12ef19-b673-4ee1-babd-726e4ce11f35","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581262 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"}]
09:56:21 INFO - 1499446581263 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:21 INFO - 1499446581265 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:21 INFO - 1499446581265 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:21 INFO - 1499446581283 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"
09:56:21 INFO - 1499446581285 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"
09:56:21 INFO - 1499446581287 Marionette TRACE 394 <- [1,2,null,{}]
09:56:21 INFO - 1499446581299 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:56:21 INFO - 1499446581301 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1cf81a81-6072-4af7-bf63-78b77a88212b","ELEMENT":"1cf81a81-6072-4af7-bf63-78b77a88212b"}}]
09:56:21 INFO - 1499446581302 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:56:21 INFO - 1499446581304 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f8fd04ec-6006-4c89-b897-ecdceb64ca85","ELEMENT":"f8fd04ec-6006-4c89-b897-ecdceb64ca85"}}]
09:56:21 INFO - 1499446581305 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"1cf81a81-6072-4af7-bf63-78b77a88212b"}]
09:56:21 INFO - 1499446581547 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:21 INFO - 1499446581547 Marionette TRACE 394 <- [1,5,null,{}]
09:56:21 INFO - 1499446581548 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"f8fd04ec-6006-4c89-b897-ecdceb64ca85"}]
09:56:21 INFO - 1499446581562 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
09:56:21 INFO - 1499446581564 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:56:21 INFO - 1499446581566 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:56:21 INFO - 1499446581567 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}]
09:56:21 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499446581569 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:56:21 INFO - 1499446581570 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:56:21 INFO - 1499446581571 Marionette TRACE 394 <- [1,9,null,{}]
09:56:21 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 367ms
09:56:21 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:56:21 INFO - 1499446581573 Marionette DEBUG Closed connection 394
09:56:21 INFO - 1499446581575 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49796
09:56:21 INFO - 1499446581575 Marionette DEBUG Closed connection 395
09:56:21 INFO - 1499446581576 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49797
09:56:21 INFO - 1499446581577 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:21 INFO - 1499446581577 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581579 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581582 Marionette TRACE 396 <- [1,1,null,{"sessionId":"91db8ec5-c186-4bec-8f19-49f06b2a7ffd","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581583 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:56:21 INFO - 1499446581584 Marionette TRACE 396 <- [1,2,null,{}]
09:56:21 INFO - 1499446581584 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:21 INFO - 1499446581585 Marionette TRACE 396 <- [1,3,null,{}]
09:56:21 INFO - 1499446581586 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:21 INFO - 1499446581586 Marionette TRACE 396 <- [1,4,null,{}]
09:56:21 INFO - 1499446581588 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}]
09:56:21 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499446581590 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:56:21 INFO - 1499446581591 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:56:21 INFO - 1499446581592 Marionette TRACE 396 <- [1,6,null,{}]
09:56:21 INFO - 1499446581594 Marionette DEBUG Closed connection 396
09:56:21 INFO - 1499446581594 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49798
09:56:21 INFO - 1499446581595 Marionette DEBUG Closed connection 397
09:56:21 INFO - 1499446581596 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49799
09:56:21 INFO - 1499446581596 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:21 INFO - 1499446581597 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581599 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581607 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5fe4f96c-c7c5-4f2f-ad2c-a105dd24eb60","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581609 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:56:21 INFO - 1499446581610 Marionette TRACE 398 <- [1,2,null,{}]
09:56:21 INFO - 1499446581612 Marionette DEBUG Closed connection 398
09:56:21 INFO - 1499446581613 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49800
09:56:21 INFO - 1499446581613 Marionette DEBUG Closed connection 399
09:56:21 INFO - 1499446581614 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49801
09:56:21 INFO - 1499446581615 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:21 INFO - 1499446581616 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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}
09:56:21 INFO - 1499446581618 Marionette DEBUG Register listener.js for window 12
09:56:21 INFO - 1499446581620 Marionette TRACE 400 <- [1,1,null,{"sessionId":"07a87ca5-4408-47d8-a601-0386123877a2","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":3608,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-301.001\\AppData\\Local\\Temp\\tmpijm43h.mozrunner","moz:accessibilityChecks":false}}]
09:56:21 INFO - 1499446581622 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"}]
09:56:21 INFO - 1499446581623 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"
09:56:21 INFO - 1499446581625 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"
09:56:21 INFO - 1499446581625 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"
09:56:21 INFO - 1499446581636 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"
09:56:21 INFO - 1499446581639 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"
09:56:21 INFO - 1499446581646 Marionette TRACE 400 <- [1,2,null,{}]
09:56:21 INFO - 1499446581648 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:56:21 INFO - 1499446581650 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e52fd4e0-49f0-46ef-b226-9fd8e49c6360","ELEMENT":"e52fd4e0-49f0-46ef-b226-9fd8e49c6360"}}]
09:56:21 INFO - 1499446581651 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e52fd4e0-49f0-46ef-b226-9fd8e49c6360"}]
09:56:21 INFO - 1499446581673 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:56:21 INFO - 1499446581674 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e52fd4e0-49f0-46ef-b226-9fd8e49c6360"}]
09:56:21 INFO - 1499446581687 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element