could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:40:04 INFO - 1499888404788 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
12:40:04 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499888404791 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:40:04 INFO - 1499888404792 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:40:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 149ms
12:40:04 INFO - 1499888404793 Marionette TRACE 352 <- [1,6,null,{}]
12:40:04 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:40:04 INFO - 1499888404795 Marionette DEBUG Closed connection 352
12:40:04 INFO - 1499888404796 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49760
12:40:04 INFO - 1499888404797 Marionette DEBUG Closed connection 353
12:40:04 INFO - 1499888404798 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49761
12:40:04 INFO - 1499888404799 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:04 INFO - 1499888404800 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:04 INFO - 1499888404803 Marionette DEBUG Register listener.js for window 12
12:40:04 INFO - 1499888404806 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f37e6257-1156-4070-819c-245f87d6d2e0","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:04 INFO - 1499888404808 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:40:04 INFO - 1499888404809 Marionette TRACE 354 <- [1,2,null,{}]
12:40:04 INFO - 1499888404810 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:40:04 INFO - 1499888404811 Marionette TRACE 354 <- [1,3,null,{}]
12:40:04 INFO - 1499888404812 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:40:04 INFO - 1499888404812 Marionette TRACE 354 <- [1,4,null,{}]
12:40:04 INFO - 1499888404813 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
12:40:04 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499888404816 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:40:04 INFO - 1499888404817 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:40:04 INFO - 1499888404819 Marionette TRACE 354 <- [1,6,null,{}]
12:40:04 INFO - 1499888404821 Marionette DEBUG Closed connection 354
12:40:04 INFO - 1499888404821 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49762
12:40:04 INFO - 1499888404822 Marionette DEBUG Closed connection 355
12:40:04 INFO - 1499888404823 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49763
12:40:04 INFO - 1499888404824 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:04 INFO - 1499888404824 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:04 INFO - 1499888404827 Marionette DEBUG Register listener.js for window 12
12:40:04 INFO - 1499888404830 Marionette TRACE 356 <- [1,1,null,{"sessionId":"50fb7613-698f-4761-ad63-77aedf4478a3","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:04 INFO - 1499888404832 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:40:04 INFO - 1499888404833 Marionette TRACE 356 <- [1,2,null,{}]
12:40:04 INFO - 1499888404836 Marionette DEBUG Closed connection 356
12:40:04 INFO - 1499888404836 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49764
12:40:04 INFO - 1499888404837 Marionette DEBUG Closed connection 357
12:40:04 INFO - 1499888404838 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49765
12:40:04 INFO - 1499888404839 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:40:04 INFO - 1499888404840 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:04 INFO - 1499888404843 Marionette DEBUG Register listener.js for window 12
12:40:04 INFO - 1499888404846 Marionette TRACE 358 <- [1,1,null,{"sessionId":"7dcf8f5b-0d7e-4e25-a49d-587da4c8e978","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:04 INFO - 1499888404848 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
12:40:04 INFO - 1499888404850 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
12:40:04 INFO - 1499888404855 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
12:40:04 INFO - 1499888404856 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
12:40:04 INFO - 1499888404872 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
12:40:04 INFO - 1499888404880 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
12:40:04 INFO - 1499888404884 Marionette TRACE 358 <- [1,2,null,{}]
12:40:04 INFO - 1499888404886 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:40:04 INFO - 1499888404888 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f59f2da1-2516-4fc2-a070-f3822cb3be6f","ELEMENT":"f59f2da1-2516-4fc2-a070-f3822cb3be6f"}}]
12:40:04 INFO - 1499888404889 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f59f2da1-2516-4fc2-a070-f3822cb3be6f"}]
12:40:04 INFO - 1499888404945 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
12:40:04 INFO - 1499888404954 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
12:40:04 INFO - 1499888404954 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
12:40:04 INFO - 1499888404971 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
12:40:04 INFO - 1499888404975 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
12:40:04 INFO - 1499888404984 Marionette TRACE 358 <- [1,4,null,{}]
12:40:04 INFO - 1499888404985 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:40:04 INFO - 1499888404988 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63e88974-5166-4233-b58b-76a3cfda3a62","ELEMENT":"63e88974-5166-4233-b58b-76a3cfda3a62"}}]
12:40:04 INFO - 1499888404988 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:40:04 INFO - 1499888404989 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:40:04 INFO - 1499888404991 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
12:40:04 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499888404995 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:40:04 INFO - 1499888404996 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:40:05 INFO - 1499888404998 Marionette TRACE 358 <- [1,8,null,{}]
12:40:05 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 203ms
12:40:05 INFO - TEST-START | test_click.py TestClick.test_container_element
12:40:05 INFO - 1499888405000 Marionette DEBUG Closed connection 358
12:40:05 INFO - 1499888405001 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49768
12:40:05 INFO - 1499888405001 Marionette DEBUG Closed connection 359
12:40:05 INFO - 1499888405002 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49769
12:40:05 INFO - 1499888405003 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:05 INFO - 1499888405004 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405007 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405010 Marionette TRACE 360 <- [1,1,null,{"sessionId":"f678fab7-667a-40ce-a191-a6a6cd91e266","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405013 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:40:05 INFO - 1499888405013 Marionette TRACE 360 <- [1,2,null,{}]
12:40:05 INFO - 1499888405014 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:40:05 INFO - 1499888405015 Marionette TRACE 360 <- [1,3,null,{}]
12:40:05 INFO - 1499888405016 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:40:05 INFO - 1499888405017 Marionette TRACE 360 <- [1,4,null,{}]
12:40:05 INFO - 1499888405018 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
12:40:05 INFO - TEST-START: test_click.py TestClick.test_container_element1499888405021 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:40:05 INFO - 1499888405022 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:40:05 INFO - 1499888405023 Marionette TRACE 360 <- [1,6,null,{}]
12:40:05 INFO - 1499888405025 Marionette DEBUG Closed connection 360
12:40:05 INFO - 1499888405026 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49770
12:40:05 INFO - 1499888405027 Marionette DEBUG Closed connection 361
12:40:05 INFO - 1499888405027 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49771
12:40:05 INFO - 1499888405028 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:05 INFO - 1499888405029 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405032 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405035 Marionette TRACE 362 <- [1,1,null,{"sessionId":"beee8518-7053-48d9-a13d-6c821d50bc1c","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405037 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:40:05 INFO - 1499888405038 Marionette TRACE 362 <- [1,2,null,{}]
12:40:05 INFO - 1499888405040 Marionette DEBUG Closed connection 362
12:40:05 INFO - 1499888405041 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49772
12:40:05 INFO - 1499888405041 Marionette DEBUG Closed connection 363
12:40:05 INFO - 1499888405042 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49773
12:40:05 INFO - 1499888405043 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:40:05 INFO - 1499888405044 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405047 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405050 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0e6dbbbc-c272-470c-ac62-18483f2ae460","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405052 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:40:05 INFO - 1499888405053 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
12:40:05 INFO - 1499888405055 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
12:40:05 INFO - 1499888405055 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
12:40:05 INFO - 1499888405080 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405084 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405087 Marionette TRACE 364 <- [1,2,null,{}]
12:40:05 INFO - 1499888405089 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:40:05 INFO - 1499888405097 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7cd1f64f-debb-47ff-86b8-3005859d1d10","ELEMENT":"7cd1f64f-debb-47ff-86b8-3005859d1d10"}}]
12:40:05 INFO - 1499888405099 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7cd1f64f-debb-47ff-86b8-3005859d1d10"}]
12:40:05 INFO - 1499888405342 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:40:05 INFO - 1499888405343 Marionette TRACE 364 <- [1,4,null,{}]
12:40:05 INFO - 1499888405344 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7cd1f64f-debb-47ff-86b8-3005859d1d10","name":"selected"}]
12:40:05 INFO - 1499888405349 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:40:05 INFO - 1499888405350 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
12:40:05 INFO - TEST-END: test_click.py TestClick.test_container_element1499888405353 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:40:05 INFO - 1499888405354 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:40:05 INFO - 1499888405355 Marionette TRACE 364 <- [1,7,null,{}]
12:40:05 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 357ms
12:40:05 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:40:05 INFO - 1499888405358 Marionette DEBUG Closed connection 364
12:40:05 INFO - 1499888405359 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49774
12:40:05 INFO - 1499888405360 Marionette DEBUG Closed connection 365
12:40:05 INFO - 1499888405361 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49775
12:40:05 INFO - 1499888405362 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:05 INFO - 1499888405362 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405365 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405368 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fa11a593-bde1-4653-9269-403e36d169a8","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405377 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:40:05 INFO - 1499888405377 Marionette TRACE 366 <- [1,2,null,{}]
12:40:05 INFO - 1499888405378 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:40:05 INFO - 1499888405379 Marionette TRACE 366 <- [1,3,null,{}]
12:40:05 INFO - 1499888405380 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:40:05 INFO - 1499888405381 Marionette TRACE 366 <- [1,4,null,{}]
12:40:05 INFO - 1499888405383 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
12:40:05 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499888405386 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:40:05 INFO - 1499888405387 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:40:05 INFO - 1499888405389 Marionette TRACE 366 <- [1,6,null,{}]
12:40:05 INFO - 1499888405391 Marionette DEBUG Closed connection 366
12:40:05 INFO - 1499888405391 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49776
12:40:05 INFO - 1499888405392 Marionette DEBUG Closed connection 367
12:40:05 INFO - 1499888405393 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49777
12:40:05 INFO - 1499888405394 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:05 INFO - 1499888405395 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405397 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405401 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f952a0b3-9706-42a8-8ec6-601abfaf0b09","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405403 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:40:05 INFO - 1499888405404 Marionette TRACE 368 <- [1,2,null,{}]
12:40:05 INFO - 1499888405406 Marionette DEBUG Closed connection 368
12:40:05 INFO - 1499888405407 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49778
12:40:05 INFO - 1499888405408 Marionette DEBUG Closed connection 369
12:40:05 INFO - 1499888405408 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49779
12:40:05 INFO - 1499888405409 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:40:05 INFO - 1499888405410 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405412 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405415 Marionette TRACE 370 <- [1,1,null,{"sessionId":"37c55545-b7e8-47b7-9d24-a1c3581550a5","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405417 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:40:05 INFO - 1499888405419 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405421 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405421 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405438 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405442 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405452 Marionette TRACE 370 <- [1,2,null,{}]
12:40:05 INFO - 1499888405453 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:40:05 INFO - 1499888405455 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c903a26-45b1-4838-98e3-8ebc857f3019","ELEMENT":"6c903a26-45b1-4838-98e3-8ebc857f3019"}}]
12:40:05 INFO - 1499888405456 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"6c903a26-45b1-4838-98e3-8ebc857f3019"}]
12:40:05 INFO - 1499888405702 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:40:05 INFO - 1499888405703 Marionette TRACE 370 <- [1,4,null,{}]
12:40:05 INFO - 1499888405704 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"6c903a26-45b1-4838-98e3-8ebc857f3019","name":"selected"}]
12:40:05 INFO - 1499888405709 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:40:05 INFO - 1499888405711 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
12:40:05 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499888405713 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:40:05 INFO - 1499888405714 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:40:05 INFO - 1499888405715 Marionette TRACE 370 <- [1,7,null,{}]
12:40:05 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 359ms
12:40:05 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:40:05 INFO - 1499888405718 Marionette DEBUG Closed connection 370
12:40:05 INFO - 1499888405718 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49780
12:40:05 INFO - 1499888405719 Marionette DEBUG Closed connection 371
12:40:05 INFO - 1499888405719 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49781
12:40:05 INFO - 1499888405720 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:05 INFO - 1499888405721 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405724 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405727 Marionette TRACE 372 <- [1,1,null,{"sessionId":"34a73f34-8b2f-4381-8669-d8e7850d294d","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405729 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:40:05 INFO - 1499888405730 Marionette TRACE 372 <- [1,2,null,{}]
12:40:05 INFO - 1499888405731 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:40:05 INFO - 1499888405731 Marionette TRACE 372 <- [1,3,null,{}]
12:40:05 INFO - 1499888405732 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:40:05 INFO - 1499888405732 Marionette TRACE 372 <- [1,4,null,{}]
12:40:05 INFO - 1499888405734 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
12:40:05 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499888405737 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:40:05 INFO - 1499888405737 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:40:05 INFO - 1499888405739 Marionette TRACE 372 <- [1,6,null,{}]
12:40:05 INFO - 1499888405741 Marionette DEBUG Closed connection 372
12:40:05 INFO - 1499888405742 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49782
12:40:05 INFO - 1499888405743 Marionette DEBUG Closed connection 373
12:40:05 INFO - 1499888405743 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49783
12:40:05 INFO - 1499888405744 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:05 INFO - 1499888405745 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405747 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405750 Marionette TRACE 374 <- [1,1,null,{"sessionId":"5d2e9aec-1b2f-44bc-bc27-e9aada3a568d","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405752 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:40:05 INFO - 1499888405754 Marionette TRACE 374 <- [1,2,null,{}]
12:40:05 INFO - 1499888405756 Marionette DEBUG Closed connection 374
12:40:05 INFO - 1499888405756 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49784
12:40:05 INFO - 1499888405759 Marionette DEBUG Closed connection 375
12:40:05 INFO - 1499888405759 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49785
12:40:05 INFO - 1499888405761 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:40:05 INFO - 1499888405761 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:05 INFO - 1499888405764 Marionette DEBUG Register listener.js for window 12
12:40:05 INFO - 1499888405767 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a36dd7d1-38cc-4203-a0d6-01ab26686612","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:05 INFO - 1499888405769 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
12:40:05 INFO - 1499888405770 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405773 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405773 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:05 INFO - 1499888405794 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:40:05 INFO - 1499888405796 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:40:05 INFO - 1499888405800 Marionette TRACE 376 <- [1,2,null,{}]
12:40:05 INFO - 1499888405801 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:40:05 INFO - 1499888405804 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a2ff65fe-659e-4209-bfc8-299c09a7f6b3","ELEMENT":"a2ff65fe-659e-4209-bfc8-299c09a7f6b3"}}]
12:40:05 INFO - 1499888405805 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"a2ff65fe-659e-4209-bfc8-299c09a7f6b3"}]
12:40:06 INFO - 1499888406058 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:40:06 INFO - 1499888406059 Marionette TRACE 376 <- [1,4,null,{}]
12:40:06 INFO - 1499888406061 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
12:40:06 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499888406063 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:40:06 INFO - 1499888406064 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:40:06 INFO - 1499888406066 Marionette TRACE 376 <- [1,6,null,{}]
12:40:06 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 350ms
12:40:06 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:40:06 INFO - 1499888406068 Marionette DEBUG Closed connection 376
12:40:06 INFO - 1499888406069 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49786
12:40:06 INFO - 1499888406070 Marionette DEBUG Closed connection 377
12:40:06 INFO - 1499888406070 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49787
12:40:06 INFO - 1499888406072 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:06 INFO - 1499888406072 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406075 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406078 Marionette TRACE 378 <- [1,1,null,{"sessionId":"46edabcf-f05a-43ef-8077-889a63f6a360","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406080 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:40:06 INFO - 1499888406080 Marionette TRACE 378 <- [1,2,null,{}]
12:40:06 INFO - 1499888406081 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:40:06 INFO - 1499888406082 Marionette TRACE 378 <- [1,3,null,{}]
12:40:06 INFO - 1499888406083 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:40:06 INFO - 1499888406083 Marionette TRACE 378 <- [1,4,null,{}]
12:40:06 INFO - 1499888406085 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
12:40:06 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499888406087 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:40:06 INFO - 1499888406088 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:40:06 INFO - 1499888406090 Marionette TRACE 378 <- [1,6,null,{}]
12:40:06 INFO - 1499888406092 Marionette DEBUG Closed connection 378
12:40:06 INFO - 1499888406093 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49788
12:40:06 INFO - 1499888406094 Marionette DEBUG Closed connection 379
12:40:06 INFO - 1499888406094 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49789
12:40:06 INFO - 1499888406095 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:06 INFO - 1499888406096 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406099 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406102 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3f4a69c8-c781-483d-bf4a-e56fd7663bde","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406103 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:40:06 INFO - 1499888406105 Marionette TRACE 380 <- [1,2,null,{}]
12:40:06 INFO - 1499888406107 Marionette DEBUG Closed connection 380
12:40:06 INFO - 1499888406108 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49790
12:40:06 INFO - 1499888406108 Marionette DEBUG Closed connection 381
12:40:06 INFO - 1499888406109 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49791
12:40:06 INFO - 1499888406110 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:40:06 INFO - 1499888406111 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406113 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406116 Marionette TRACE 382 <- [1,1,null,{"sessionId":"863c4cb0-2715-42d4-af4e-9f7e5d56911d","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406118 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:40:06 INFO - 1499888406120 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:40:06 INFO - 1499888406122 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:40:06 INFO - 1499888406123 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
12:40:06 INFO - 1499888406145 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:06 INFO - 1499888406147 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:06 INFO - 1499888406150 Marionette TRACE 382 <- [1,2,null,{}]
12:40:06 INFO - 1499888406151 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:40:06 INFO - 1499888406154 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"438a2d2a-8dfb-4300-b7a0-a177e0eabcf7","ELEMENT":"438a2d2a-8dfb-4300-b7a0-a177e0eabcf7"}}]
12:40:06 INFO - 1499888406155 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"438a2d2a-8dfb-4300-b7a0-a177e0eabcf7"}]
12:40:06 INFO - 1499888406409 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:40:06 INFO - 1499888406409 Marionette TRACE 382 <- [1,4,null,{}]
12:40:06 INFO - 1499888406411 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
12:40:06 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499888406414 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:40:06 INFO - 1499888406414 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:40:06 INFO - 1499888406416 Marionette TRACE 382 <- [1,6,null,{}]
12:40:06 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 349ms
12:40:06 INFO - TEST-START | test_click.py TestClick.test_input_file
12:40:06 INFO - 1499888406418 Marionette DEBUG Closed connection 382
12:40:06 INFO - 1499888406419 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49792
12:40:06 INFO - 1499888406420 Marionette DEBUG Closed connection 383
12:40:06 INFO - 1499888406421 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49793
12:40:06 INFO - 1499888406422 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:06 INFO - 1499888406422 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406425 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406428 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ca926927-9b37-490e-a589-1c39e33438e5","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406430 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:40:06 INFO - 1499888406430 Marionette TRACE 384 <- [1,2,null,{}]
12:40:06 INFO - 1499888406431 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:40:06 INFO - 1499888406432 Marionette TRACE 384 <- [1,3,null,{}]
12:40:06 INFO - 1499888406433 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:40:06 INFO - 1499888406433 Marionette TRACE 384 <- [1,4,null,{}]
12:40:06 INFO - 1499888406434 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
12:40:06 INFO - TEST-START: test_click.py TestClick.test_input_file1499888406437 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:40:06 INFO - 1499888406438 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:40:06 INFO - 1499888406439 Marionette TRACE 384 <- [1,6,null,{}]
12:40:06 INFO - 1499888406441 Marionette DEBUG Closed connection 384
12:40:06 INFO - 1499888406442 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49794
12:40:06 INFO - 1499888406443 Marionette DEBUG Closed connection 385
12:40:06 INFO - 1499888406443 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49795
12:40:06 INFO - 1499888406444 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:06 INFO - 1499888406445 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406447 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406457 Marionette TRACE 386 <- [1,1,null,{"sessionId":"fcb7cc58-b2bd-44f3-ae27-a9628e6ecee2","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406460 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:40:06 INFO - 1499888406461 Marionette TRACE 386 <- [1,2,null,{}]
12:40:06 INFO - 1499888406464 Marionette DEBUG Closed connection 386
12:40:06 INFO - 1499888406465 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49796
12:40:06 INFO - 1499888406466 Marionette DEBUG Closed connection 387
12:40:06 INFO - 1499888406467 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49797
12:40:06 INFO - 1499888406468 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:40:06 INFO - 1499888406469 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406471 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406474 Marionette TRACE 388 <- [1,1,null,{"sessionId":"909964a7-faef-4ba6-bf4b-c09ba9092d95","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406476 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:40:06 INFO - 1499888406478 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:06 INFO - 1499888406480 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:06 INFO - 1499888406480 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
12:40:06 INFO - 1499888406491 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:40:06 INFO - 1499888406501 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:40:06 INFO - 1499888406505 Marionette TRACE 388 <- [1,2,null,{}]
12:40:06 INFO - 1499888406506 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:40:06 INFO - 1499888406514 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d29ab117-f301-4133-9a3d-844ec81f2ef1","ELEMENT":"d29ab117-f301-4133-9a3d-844ec81f2ef1"}}]
12:40:06 INFO - 1499888406516 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d29ab117-f301-4133-9a3d-844ec81f2ef1"}]
12:40:06 INFO - 1499888406545 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:40:06 INFO - 1499888406546 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
12:40:06 INFO - TEST-END: test_click.py TestClick.test_input_file1499888406549 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:40:06 INFO - 1499888406550 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:40:06 INFO - 1499888406551 Marionette TRACE 388 <- [1,6,null,{}]
12:40:06 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 135ms
12:40:06 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:40:06 INFO - 1499888406554 Marionette DEBUG Closed connection 388
12:40:06 INFO - 1499888406554 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49798
12:40:06 INFO - 1499888406556 Marionette DEBUG Closed connection 389
12:40:06 INFO - 1499888406556 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49799
12:40:06 INFO - 1499888406557 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:06 INFO - 1499888406558 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406560 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406563 Marionette TRACE 390 <- [1,1,null,{"sessionId":"eecdb137-08ff-485a-b0a9-9e1dc57fdec8","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406566 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:40:06 INFO - 1499888406566 Marionette TRACE 390 <- [1,2,null,{}]
12:40:06 INFO - 1499888406567 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:40:06 INFO - 1499888406567 Marionette TRACE 390 <- [1,3,null,{}]
12:40:06 INFO - 1499888406568 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:40:06 INFO - 1499888406569 Marionette TRACE 390 <- [1,4,null,{}]
12:40:06 INFO - 1499888406571 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
12:40:06 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499888406573 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:40:06 INFO - 1499888406574 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:40:06 INFO - 1499888406576 Marionette TRACE 390 <- [1,6,null,{}]
12:40:06 INFO - 1499888406578 Marionette DEBUG Closed connection 390
12:40:06 INFO - 1499888406579 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49800
12:40:06 INFO - 1499888406579 Marionette DEBUG Closed connection 391
12:40:06 INFO - 1499888406580 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49801
12:40:06 INFO - 1499888406581 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:06 INFO - 1499888406581 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406584 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406587 Marionette TRACE 392 <- [1,1,null,{"sessionId":"899d666b-6fcf-42a9-8381-809f98165cb0","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406589 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:40:06 INFO - 1499888406590 Marionette TRACE 392 <- [1,2,null,{}]
12:40:06 INFO - 1499888406593 Marionette DEBUG Closed connection 392
12:40:06 INFO - 1499888406594 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49802
12:40:06 INFO - 1499888406595 Marionette DEBUG Closed connection 393
12:40:06 INFO - 1499888406595 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49803
12:40:06 INFO - 1499888406596 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:40:06 INFO - 1499888406597 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406599 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406602 Marionette TRACE 394 <- [1,1,null,{"sessionId":"904d7a02-a5bb-46c5-8798-886714633e0a","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406604 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
12:40:06 INFO - 1499888406606 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:40:06 INFO - 1499888406608 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:40:06 INFO - 1499888406608 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:40:06 INFO - 1499888406628 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:40:06 INFO - 1499888406631 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:40:06 INFO - 1499888406634 Marionette TRACE 394 <- [1,2,null,{}]
12:40:06 INFO - 1499888406635 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:40:06 INFO - 1499888406637 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0fad3116-aea5-4e36-927b-44afe258ae15","ELEMENT":"0fad3116-aea5-4e36-927b-44afe258ae15"}}]
12:40:06 INFO - 1499888406638 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:40:06 INFO - 1499888406640 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"65132ec9-83a8-4b59-b97c-296e544e6534","ELEMENT":"65132ec9-83a8-4b59-b97c-296e544e6534"}}]
12:40:06 INFO - 1499888406642 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"0fad3116-aea5-4e36-927b-44afe258ae15"}]
12:40:06 INFO - 1499888406914 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:40:06 INFO - 1499888406915 Marionette TRACE 394 <- [1,5,null,{}]
12:40:06 INFO - 1499888406918 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"65132ec9-83a8-4b59-b97c-296e544e6534"}]
12:40:06 INFO - 1499888406940 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
12:40:06 INFO - 1499888406942 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:40:06 INFO - 1499888406947 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:40:06 INFO - 1499888406949 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
12:40:06 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499888406953 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:40:06 INFO - 1499888406955 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:40:06 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 406ms
12:40:06 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:40:06 INFO - 1499888406958 Marionette TRACE 394 <- [1,9,null,{}]
12:40:06 INFO - 1499888406962 Marionette DEBUG Closed connection 394
12:40:06 INFO - 1499888406963 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49804
12:40:06 INFO - 1499888406974 Marionette DEBUG Closed connection 395
12:40:06 INFO - 1499888406975 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49805
12:40:06 INFO - 1499888406977 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:06 INFO - 1499888406980 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:06 INFO - 1499888406984 Marionette DEBUG Register listener.js for window 12
12:40:06 INFO - 1499888406990 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ed8460e6-fad4-46e3-b349-0b7fbf0cd0f6","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:06 INFO - 1499888406994 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:40:06 INFO - 1499888406995 Marionette TRACE 396 <- [1,2,null,{}]
12:40:06 INFO - 1499888406996 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:40:06 INFO - 1499888406997 Marionette TRACE 396 <- [1,3,null,{}]
12:40:07 INFO - 1499888406999 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:40:07 INFO - 1499888406999 Marionette TRACE 396 <- [1,4,null,{}]
12:40:07 INFO - 1499888407002 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
12:40:07 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499888407006 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:40:07 INFO - 1499888407007 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:40:07 INFO - 1499888407009 Marionette TRACE 396 <- [1,6,null,{}]
12:40:07 INFO - 1499888407015 Marionette DEBUG Closed connection 396
12:40:07 INFO - 1499888407016 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49806
12:40:07 INFO - 1499888407018 Marionette DEBUG Closed connection 397
12:40:07 INFO - 1499888407018 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49807
12:40:07 INFO - 1499888407021 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:40:07 INFO - 1499888407023 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:07 INFO - 1499888407026 Marionette DEBUG Register listener.js for window 12
12:40:07 INFO - 1499888407031 Marionette TRACE 398 <- [1,1,null,{"sessionId":"498a6074-2a09-4d37-be46-5b4e554bebee","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:07 INFO - 1499888407034 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:40:07 INFO - 1499888407036 Marionette TRACE 398 <- [1,2,null,{}]
12:40:07 INFO - 1499888407038 Marionette DEBUG Closed connection 398
12:40:07 INFO - 1499888407039 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49808
12:40:07 INFO - 1499888407041 Marionette DEBUG Closed connection 399
12:40:07 INFO - 1499888407041 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49809
12:40:07 INFO - 1499888407044 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:40:07 INFO - 1499888407045 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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}
12:40:07 INFO - 1499888407048 Marionette DEBUG Register listener.js for window 12
12:40:07 INFO - 1499888407052 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4d4035c8-2f67-49a4-8a51-fde2f8c012ed","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":3140,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-306.001\\AppData\\Local\\Temp\\tmpbprrti.mozrunner","moz:accessibilityChecks":false}}]
12:40:07 INFO - 1499888407055 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
12:40:07 INFO - 1499888407057 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:40:07 INFO - 1499888407068 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:40:07 INFO - 1499888407069 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
12:40:07 INFO - 1499888407090 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
12:40:07 INFO - 1499888407094 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
12:40:07 INFO - 1499888407115 Marionette TRACE 400 <- [1,2,null,{}]
12:40:07 INFO - 1499888407117 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:40:07 INFO - 1499888407120 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f7a040f7-3d85-43b5-a725-89a7314d5fd7","ELEMENT":"f7a040f7-3d85-43b5-a725-89a7314d5fd7"}}]
12:40:07 INFO - 1499888407121 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f7a040f7-3d85-43b5-a725-89a7314d5fd7"}]
12:40:07 INFO - 1499888407148 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:40:07 INFO - 1499888407153 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f7a040f7-3d85-43b5-a725-89a7314d5fd7"}]
12:40:07 INFO - 1499888407172 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element