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]
05:15:31 INFO - 1500466531786 Marionette TRACE 353 -> [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}]
05:15:31 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500466531789 Marionette TRACE 353 <- [1,5,null,{"value":null}]
05:15:31 INFO - 1500466531790 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
05:15:31 INFO - 1500466531791 Marionette TRACE 353 <- [1,6,null,{}]
05:15:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 145ms
05:15:31 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:15:31 INFO - 1500466531793 Marionette DEBUG Closed connection 353
05:15:31 INFO - 1500466531794 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49780
05:15:31 INFO - 1500466531795 Marionette DEBUG Closed connection 354
05:15:31 INFO - 1500466531795 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49781
05:15:31 INFO - 1500466531796 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:31 INFO - 1500466531796 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:31 INFO - 1500466531798 Marionette DEBUG Register listener.js for window 15
05:15:31 INFO - 1500466531801 Marionette TRACE 355 <- [1,1,null,{"sessionId":"6cc38b71-6bbe-40ed-af9a-96dac4ee04ce","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:31 INFO - 1500466531803 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
05:15:31 INFO - 1500466531803 Marionette TRACE 355 <- [1,2,null,{}]
05:15:31 INFO - 1500466531804 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:15:31 INFO - 1500466531804 Marionette TRACE 355 <- [1,3,null,{}]
05:15:31 INFO - 1500466531805 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
05:15:31 INFO - 1500466531806 Marionette TRACE 355 <- [1,4,null,{}]
05:15:31 INFO - 1500466531807 Marionette TRACE 355 -> [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}]
05:15:31 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500466531809 Marionette TRACE 355 <- [1,5,null,{"value":null}]
05:15:31 INFO - 1500466531810 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
05:15:31 INFO - 1500466531811 Marionette TRACE 355 <- [1,6,null,{}]
05:15:31 INFO - 1500466531813 Marionette DEBUG Closed connection 355
05:15:31 INFO - 1500466531814 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49782
05:15:31 INFO - 1500466531814 Marionette DEBUG Closed connection 356
05:15:31 INFO - 1500466531815 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49783
05:15:31 INFO - 1500466531816 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:31 INFO - 1500466531816 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:31 INFO - 1500466531818 Marionette DEBUG Register listener.js for window 15
05:15:31 INFO - 1500466531821 Marionette TRACE 357 <- [1,1,null,{"sessionId":"2e7cfc76-c6b7-44ee-8d5a-c8adb95aa1dc","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:31 INFO - 1500466531823 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
05:15:31 INFO - 1500466531824 Marionette TRACE 357 <- [1,2,null,{}]
05:15:31 INFO - 1500466531825 Marionette DEBUG Closed connection 357
05:15:31 INFO - 1500466531826 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49784
05:15:31 INFO - 1500466531826 Marionette DEBUG Closed connection 358
05:15:31 INFO - 1500466531827 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49785
05:15:31 INFO - 1500466531828 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:15:31 INFO - 1500466531828 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:31 INFO - 1500466531830 Marionette DEBUG Register listener.js for window 15
05:15:31 INFO - 1500466531833 Marionette TRACE 359 <- [1,1,null,{"sessionId":"f7664a03-b84d-4816-bd44-e1f38f3e0309","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:31 INFO - 1500466531835 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49354/clicks.html"}]
05:15:31 INFO - 1500466531836 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49354/hidden.html"
05:15:31 INFO - 1500466531840 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49354/hidden.html"
05:15:31 INFO - 1500466531840 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49354/hidden.html"
05:15:31 INFO - 1500466531855 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49354/clicks.html"
05:15:31 INFO - 1500466531859 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49354/clicks.html"
05:15:31 INFO - 1500466531863 Marionette TRACE 359 <- [1,2,null,{}]
05:15:31 INFO - 1500466531869 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:15:31 INFO - 1500466531873 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b160da12-638e-4b17-95d1-eff068ce958f","ELEMENT":"b160da12-638e-4b17-95d1-eff068ce958f"}}]
05:15:31 INFO - 1500466531874 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"b160da12-638e-4b17-95d1-eff068ce958f"}]
05:15:31 INFO - 1500466531918 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49354/clicks.html"
05:15:31 INFO - 1500466531922 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49354/clicks.html"
05:15:31 INFO - 1500466531923 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49354/clicks.html"
05:15:31 INFO - 1500466531939 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49354/test.html"
05:15:31 INFO - 1500466531941 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49354/test.html"
05:15:31 INFO - 1500466531943 Marionette TRACE 359 <- [1,4,null,{}]
05:15:31 INFO - 1500466531949 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:15:31 INFO - 1500466531951 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b5e0060f-517a-449a-89d3-b91f2ca2cb6b","ELEMENT":"b5e0060f-517a-449a-89d3-b91f2ca2cb6b"}}]
05:15:31 INFO - 1500466531952 Marionette TRACE 359 -> [0,6,"getTitle",{}]
05:15:31 INFO - 1500466531953 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
05:15:31 INFO - 1500466531954 Marionette TRACE 359 -> [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}]
05:15:31 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500466531956 Marionette TRACE 359 <- [1,7,null,{"value":null}]
05:15:31 INFO - 1500466531957 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
05:15:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 167ms
05:15:31 INFO - TEST-START | test_click.py TestClick.test_container_element
05:15:31 INFO - 1500466531958 Marionette TRACE 359 <- [1,8,null,{}]
05:15:31 INFO - 1500466531960 Marionette DEBUG Closed connection 359
05:15:31 INFO - 1500466531961 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49788
05:15:31 INFO - 1500466531963 Marionette DEBUG Closed connection 360
05:15:31 INFO - 1500466531964 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49789
05:15:31 INFO - 1500466531965 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:31 INFO - 1500466531965 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:31 INFO - 1500466531968 Marionette DEBUG Register listener.js for window 15
05:15:31 INFO - 1500466531971 Marionette TRACE 361 <- [1,1,null,{"sessionId":"6835ff3c-3281-4727-a274-c04dced7b025","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:31 INFO - 1500466531972 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
05:15:31 INFO - 1500466531973 Marionette TRACE 361 <- [1,2,null,{}]
05:15:31 INFO - 1500466531973 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:15:31 INFO - 1500466531974 Marionette TRACE 361 <- [1,3,null,{}]
05:15:31 INFO - 1500466531975 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
05:15:31 INFO - 1500466531975 Marionette TRACE 361 <- [1,4,null,{}]
05:15:31 INFO - 1500466531976 Marionette TRACE 361 -> [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}]
05:15:31 INFO - TEST-START: test_click.py TestClick.test_container_element1500466531980 Marionette TRACE 361 <- [1,5,null,{"value":null}]
05:15:31 INFO - 1500466531981 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
05:15:31 INFO - 1500466531982 Marionette TRACE 361 <- [1,6,null,{}]
05:15:31 INFO - 1500466531984 Marionette DEBUG Closed connection 361
05:15:31 INFO - 1500466531984 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49790
05:15:31 INFO - 1500466531985 Marionette DEBUG Closed connection 362
05:15:31 INFO - 1500466531985 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49791
05:15:31 INFO - 1500466531986 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:31 INFO - 1500466531987 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:31 INFO - 1500466531989 Marionette DEBUG Register listener.js for window 15
05:15:31 INFO - 1500466531992 Marionette TRACE 363 <- [1,1,null,{"sessionId":"ea54374a-ec69-41cf-9543-4faee98b00ad","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:31 INFO - 1500466531994 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
05:15:31 INFO - 1500466531995 Marionette TRACE 363 <- [1,2,null,{}]
05:15:32 INFO - 1500466531998 Marionette DEBUG Closed connection 363
05:15:32 INFO - 1500466531998 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49792
05:15:32 INFO - 1500466531999 Marionette DEBUG Closed connection 364
05:15:32 INFO - 1500466532000 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49793
05:15:32 INFO - 1500466532000 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:15:32 INFO - 1500466532001 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532003 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532005 Marionette TRACE 365 <- [1,1,null,{"sessionId":"0ad51568-446e-44fb-8453-6e7ade0fe8fb","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532007 Marionette TRACE 365 -> [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"}]
05:15:32 INFO - 1500466532009 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49354/test.html"
05:15:32 INFO - 1500466532010 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49354/test.html"
05:15:32 INFO - 1500466532010 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49354/test.html"
05:15:32 INFO - 1500466532032 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"
05:15:32 INFO - 1500466532033 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"
05:15:32 INFO - 1500466532037 Marionette TRACE 365 <- [1,2,null,{}]
05:15:32 INFO - 1500466532038 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:15:32 INFO - 1500466532040 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a1fd56c3-46fd-46dd-aa52-770ad177298a","ELEMENT":"a1fd56c3-46fd-46dd-aa52-770ad177298a"}}]
05:15:32 INFO - 1500466532041 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"a1fd56c3-46fd-46dd-aa52-770ad177298a"}]
05:15:32 INFO - 1500466532282 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:15:32 INFO - 1500466532283 Marionette TRACE 365 <- [1,4,null,{}]
05:15:32 INFO - 1500466532284 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"a1fd56c3-46fd-46dd-aa52-770ad177298a","name":"selected"}]
05:15:32 INFO - 1500466532288 Marionette TRACE 365 <- [1,5,null,{"value":true}]
05:15:32 INFO - 1500466532289 Marionette TRACE 365 -> [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}]
05:15:32 INFO - TEST-END: test_click.py TestClick.test_container_element1500466532291 Marionette TRACE 365 <- [1,6,null,{"value":null}]
05:15:32 INFO - 1500466532292 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
05:15:32 INFO - 1500466532293 Marionette TRACE 365 <- [1,7,null,{}]
05:15:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 335ms
05:15:32 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:15:32 INFO - 1500466532295 Marionette DEBUG Closed connection 365
05:15:32 INFO - 1500466532296 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49794
05:15:32 INFO - 1500466532297 Marionette DEBUG Closed connection 366
05:15:32 INFO - 1500466532297 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49795
05:15:32 INFO - 1500466532298 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:32 INFO - 1500466532299 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532301 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532303 Marionette TRACE 367 <- [1,1,null,{"sessionId":"c5badb3e-0aec-48ee-960f-b9a49db7bba5","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532305 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
05:15:32 INFO - 1500466532305 Marionette TRACE 367 <- [1,2,null,{}]
05:15:32 INFO - 1500466532306 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:15:32 INFO - 1500466532306 Marionette TRACE 367 <- [1,3,null,{}]
05:15:32 INFO - 1500466532307 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
05:15:32 INFO - 1500466532309 Marionette TRACE 367 <- [1,4,null,{}]
05:15:32 INFO - 1500466532310 Marionette TRACE 367 -> [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}]
05:15:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500466532312 Marionette TRACE 367 <- [1,5,null,{"value":null}]
05:15:32 INFO - 1500466532313 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
05:15:32 INFO - 1500466532314 Marionette TRACE 367 <- [1,6,null,{}]
05:15:32 INFO - 1500466532316 Marionette DEBUG Closed connection 367
05:15:32 INFO - 1500466532316 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49796
05:15:32 INFO - 1500466532317 Marionette DEBUG Closed connection 368
05:15:32 INFO - 1500466532317 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49797
05:15:32 INFO - 1500466532318 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:32 INFO - 1500466532319 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532321 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532324 Marionette TRACE 369 <- [1,1,null,{"sessionId":"53e3a14b-7873-42a8-a0e0-66e1451726e3","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532326 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
05:15:32 INFO - 1500466532326 Marionette TRACE 369 <- [1,2,null,{}]
05:15:32 INFO - 1500466532328 Marionette DEBUG Closed connection 369
05:15:32 INFO - 1500466532329 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49798
05:15:32 INFO - 1500466532329 Marionette DEBUG Closed connection 370
05:15:32 INFO - 1500466532330 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49799
05:15:32 INFO - 1500466532331 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:15:32 INFO - 1500466532332 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532333 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532343 Marionette TRACE 371 <- [1,1,null,{"sessionId":"91754705-cf41-4cd2-81c8-8ab5fb45f34f","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532345 Marionette TRACE 371 -> [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"}]
05:15:32 INFO - 1500466532346 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"
05:15:32 INFO - 1500466532348 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"
05:15:32 INFO - 1500466532348 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"
05:15:32 INFO - 1500466532357 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"
05:15:32 INFO - 1500466532361 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"
05:15:32 INFO - 1500466532371 Marionette TRACE 371 <- [1,2,null,{}]
05:15:32 INFO - 1500466532372 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:15:32 INFO - 1500466532375 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf8daa60-6da0-4a06-99c4-2f5dfcf9cb7e","ELEMENT":"bf8daa60-6da0-4a06-99c4-2f5dfcf9cb7e"}}]
05:15:32 INFO - 1500466532376 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"bf8daa60-6da0-4a06-99c4-2f5dfcf9cb7e"}]
05:15:32 INFO - 1500466532615 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:15:32 INFO - 1500466532616 Marionette TRACE 371 <- [1,4,null,{}]
05:15:32 INFO - 1500466532617 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"bf8daa60-6da0-4a06-99c4-2f5dfcf9cb7e","name":"selected"}]
05:15:32 INFO - 1500466532621 Marionette TRACE 371 <- [1,5,null,{"value":true}]
05:15:32 INFO - 1500466532622 Marionette TRACE 371 -> [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}]
05:15:32 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500466532624 Marionette TRACE 371 <- [1,6,null,{"value":null}]
05:15:32 INFO - 1500466532625 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
05:15:32 INFO - 1500466532626 Marionette TRACE 371 <- [1,7,null,{}]
05:15:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 332ms
05:15:32 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:15:32 INFO - 1500466532628 Marionette DEBUG Closed connection 371
05:15:32 INFO - 1500466532629 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49800
05:15:32 INFO - 1500466532630 Marionette DEBUG Closed connection 372
05:15:32 INFO - 1500466532630 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49801
05:15:32 INFO - 1500466532631 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:32 INFO - 1500466532632 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532634 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532636 Marionette TRACE 373 <- [1,1,null,{"sessionId":"63f036c5-9d5d-4843-bce0-3cf7611d13fb","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532638 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
05:15:32 INFO - 1500466532638 Marionette TRACE 373 <- [1,2,null,{}]
05:15:32 INFO - 1500466532639 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:15:32 INFO - 1500466532639 Marionette TRACE 373 <- [1,3,null,{}]
05:15:32 INFO - 1500466532640 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
05:15:32 INFO - 1500466532641 Marionette TRACE 373 <- [1,4,null,{}]
05:15:32 INFO - 1500466532642 Marionette TRACE 373 -> [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}]
05:15:32 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500466532644 Marionette TRACE 373 <- [1,5,null,{"value":null}]
05:15:32 INFO - 1500466532645 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
05:15:32 INFO - 1500466532646 Marionette TRACE 373 <- [1,6,null,{}]
05:15:32 INFO - 1500466532648 Marionette DEBUG Closed connection 373
05:15:32 INFO - 1500466532648 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49802
05:15:32 INFO - 1500466532649 Marionette DEBUG Closed connection 374
05:15:32 INFO - 1500466532649 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49803
05:15:32 INFO - 1500466532650 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:32 INFO - 1500466532651 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532652 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532655 Marionette TRACE 375 <- [1,1,null,{"sessionId":"4bed38b9-5a0c-464a-ad82-70671313198b","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532656 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
05:15:32 INFO - 1500466532657 Marionette TRACE 375 <- [1,2,null,{}]
05:15:32 INFO - 1500466532659 Marionette DEBUG Closed connection 375
05:15:32 INFO - 1500466532659 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49804
05:15:32 INFO - 1500466532660 Marionette DEBUG Closed connection 376
05:15:32 INFO - 1500466532661 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49805
05:15:32 INFO - 1500466532662 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:15:32 INFO - 1500466532662 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532664 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532667 Marionette TRACE 377 <- [1,1,null,{"sessionId":"1bdf37cd-7548-4814-bed9-368bd96db49f","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532668 Marionette TRACE 377 -> [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"}]
05:15:32 INFO - 1500466532670 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"
05:15:32 INFO - 1500466532671 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"
05:15:32 INFO - 1500466532671 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"
05:15:32 INFO - 1500466532686 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"
05:15:32 INFO - 1500466532688 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"
05:15:32 INFO - 1500466532691 Marionette TRACE 377 <- [1,2,null,{}]
05:15:32 INFO - 1500466532692 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:15:32 INFO - 1500466532699 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8be638f9-18d5-4b97-aa1b-4bc363fd6fed","ELEMENT":"8be638f9-18d5-4b97-aa1b-4bc363fd6fed"}}]
05:15:32 INFO - 1500466532701 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"8be638f9-18d5-4b97-aa1b-4bc363fd6fed"}]
05:15:32 INFO - 1500466532943 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:15:32 INFO - 1500466532944 Marionette TRACE 377 <- [1,4,null,{}]
05:15:32 INFO - 1500466532946 Marionette TRACE 377 -> [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}]
05:15:32 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500466532948 Marionette TRACE 377 <- [1,5,null,{"value":null}]
05:15:32 INFO - 1500466532949 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
05:15:32 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 323ms
05:15:32 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:15:32 INFO - 1500466532950 Marionette TRACE 377 <- [1,6,null,{}]
05:15:32 INFO - 1500466532952 Marionette DEBUG Closed connection 377
05:15:32 INFO - 1500466532953 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49806
05:15:32 INFO - 1500466532953 Marionette DEBUG Closed connection 378
05:15:32 INFO - 1500466532954 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49807
05:15:32 INFO - 1500466532955 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:32 INFO - 1500466532955 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532957 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532960 Marionette TRACE 379 <- [1,1,null,{"sessionId":"ee8c64b1-71dc-4a94-984e-654f4e6a343d","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532962 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
05:15:32 INFO - 1500466532962 Marionette TRACE 379 <- [1,2,null,{}]
05:15:32 INFO - 1500466532963 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:15:32 INFO - 1500466532963 Marionette TRACE 379 <- [1,3,null,{}]
05:15:32 INFO - 1500466532964 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
05:15:32 INFO - 1500466532964 Marionette TRACE 379 <- [1,4,null,{}]
05:15:32 INFO - 1500466532966 Marionette TRACE 379 -> [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}]
05:15:32 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500466532968 Marionette TRACE 379 <- [1,5,null,{"value":null}]
05:15:32 INFO - 1500466532969 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
05:15:32 INFO - 1500466532970 Marionette TRACE 379 <- [1,6,null,{}]
05:15:32 INFO - 1500466532972 Marionette DEBUG Closed connection 379
05:15:32 INFO - 1500466532972 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49808
05:15:32 INFO - 1500466532973 Marionette DEBUG Closed connection 380
05:15:32 INFO - 1500466532973 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49809
05:15:32 INFO - 1500466532974 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:32 INFO - 1500466532974 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532976 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532979 Marionette TRACE 381 <- [1,1,null,{"sessionId":"264999ed-66e0-4aa4-b6e7-d87181632512","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532981 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
05:15:32 INFO - 1500466532982 Marionette TRACE 381 <- [1,2,null,{}]
05:15:32 INFO - 1500466532983 Marionette DEBUG Closed connection 381
05:15:32 INFO - 1500466532984 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49810
05:15:32 INFO - 1500466532985 Marionette DEBUG Closed connection 382
05:15:32 INFO - 1500466532985 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49811
05:15:32 INFO - 1500466532986 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:15:32 INFO - 1500466532986 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:32 INFO - 1500466532988 Marionette DEBUG Register listener.js for window 15
05:15:32 INFO - 1500466532991 Marionette TRACE 383 <- [1,1,null,{"sessionId":"c7052a18-d962-4116-994a-bc9b5bf17636","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:32 INFO - 1500466532993 Marionette TRACE 383 -> [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"}]
05:15:32 INFO - 1500466532994 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"
05:15:32 INFO - 1500466532995 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"
05:15:32 INFO - 1500466532996 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"
05:15:33 INFO - 1500466533005 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"
05:15:33 INFO - 1500466533008 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"
05:15:33 INFO - 1500466533011 Marionette TRACE 383 <- [1,2,null,{}]
05:15:33 INFO - 1500466533019 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:15:33 INFO - 1500466533021 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e7c9216-1959-4f92-b254-a3b18b1a9dfc","ELEMENT":"6e7c9216-1959-4f92-b254-a3b18b1a9dfc"}}]
05:15:33 INFO - 1500466533022 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"6e7c9216-1959-4f92-b254-a3b18b1a9dfc"}]
05:15:33 INFO - 1500466533259 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:15:33 INFO - 1500466533260 Marionette TRACE 383 <- [1,4,null,{}]
05:15:33 INFO - 1500466533261 Marionette TRACE 383 -> [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}]
05:15:33 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500466533263 Marionette TRACE 383 <- [1,5,null,{"value":null}]
05:15:33 INFO - 1500466533264 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
05:15:33 INFO - 1500466533265 Marionette TRACE 383 <- [1,6,null,{}]
05:15:33 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 315ms
05:15:33 INFO - TEST-START | test_click.py TestClick.test_input_file
05:15:33 INFO - 1500466533267 Marionette DEBUG Closed connection 383
05:15:33 INFO - 1500466533268 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49812
05:15:33 INFO - 1500466533269 Marionette DEBUG Closed connection 384
05:15:33 INFO - 1500466533269 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49813
05:15:33 INFO - 1500466533270 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:33 INFO - 1500466533271 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533273 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533275 Marionette TRACE 385 <- [1,1,null,{"sessionId":"688c03dc-0ab5-42fc-ab14-489645d62241","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533277 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
05:15:33 INFO - 1500466533277 Marionette TRACE 385 <- [1,2,null,{}]
05:15:33 INFO - 1500466533278 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:15:33 INFO - 1500466533278 Marionette TRACE 385 <- [1,3,null,{}]
05:15:33 INFO - 1500466533279 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
05:15:33 INFO - 1500466533280 Marionette TRACE 385 <- [1,4,null,{}]
05:15:33 INFO - 1500466533281 Marionette TRACE 385 -> [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}]
05:15:33 INFO - TEST-START: test_click.py TestClick.test_input_file1500466533283 Marionette TRACE 385 <- [1,5,null,{"value":null}]
05:15:33 INFO - 1500466533284 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
05:15:33 INFO - 1500466533285 Marionette TRACE 385 <- [1,6,null,{}]
05:15:33 INFO - 1500466533287 Marionette DEBUG Closed connection 385
05:15:33 INFO - 1500466533287 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49814
05:15:33 INFO - 1500466533288 Marionette DEBUG Closed connection 386
05:15:33 INFO - 1500466533288 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49815
05:15:33 INFO - 1500466533289 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:33 INFO - 1500466533290 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533292 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533294 Marionette TRACE 387 <- [1,1,null,{"sessionId":"e0894279-8204-4e55-919e-f20a5743c382","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533296 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
05:15:33 INFO - 1500466533297 Marionette TRACE 387 <- [1,2,null,{}]
05:15:33 INFO - 1500466533298 Marionette DEBUG Closed connection 387
05:15:33 INFO - 1500466533299 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49816
05:15:33 INFO - 1500466533299 Marionette DEBUG Closed connection 388
05:15:33 INFO - 1500466533300 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49817
05:15:33 INFO - 1500466533301 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:15:33 INFO - 1500466533301 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533303 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533306 Marionette TRACE 389 <- [1,1,null,{"sessionId":"97ec17fc-2ee4-4ddf-91b3-637c31c92f18","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533308 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:15:33 INFO - 1500466533309 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"
05:15:33 INFO - 1500466533310 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"
05:15:33 INFO - 1500466533311 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"
05:15:33 INFO - 1500466533326 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:15:33 INFO - 1500466533332 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:15:33 INFO - 1500466533335 Marionette TRACE 389 <- [1,2,null,{}]
05:15:33 INFO - 1500466533336 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:15:33 INFO - 1500466533338 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e7db55e1-334e-4cf0-9db7-4ebdd8eb1ea5","ELEMENT":"e7db55e1-334e-4cf0-9db7-4ebdd8eb1ea5"}}]
05:15:33 INFO - 1500466533339 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"e7db55e1-334e-4cf0-9db7-4ebdd8eb1ea5"}]
05:15:33 INFO - 1500466533375 Marionette TRACE 389 <- [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]
05:15:33 INFO - 1500466533377 Marionette TRACE 389 -> [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}]
05:15:33 INFO - TEST-END: test_click.py TestClick.test_input_file1500466533379 Marionette TRACE 389 <- [1,5,null,{"value":null}]
05:15:33 INFO - 1500466533380 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
05:15:33 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 115ms
05:15:33 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:15:33 INFO - 1500466533381 Marionette TRACE 389 <- [1,6,null,{}]
05:15:33 INFO - 1500466533383 Marionette DEBUG Closed connection 389
05:15:33 INFO - 1500466533384 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49818
05:15:33 INFO - 1500466533385 Marionette DEBUG Closed connection 390
05:15:33 INFO - 1500466533385 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49819
05:15:33 INFO - 1500466533386 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:33 INFO - 1500466533387 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533389 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533391 Marionette TRACE 391 <- [1,1,null,{"sessionId":"3e6eaa81-47c8-4b5b-9f3b-69bb6a92d727","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533393 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
05:15:33 INFO - 1500466533393 Marionette TRACE 391 <- [1,2,null,{}]
05:15:33 INFO - 1500466533394 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:15:33 INFO - 1500466533395 Marionette TRACE 391 <- [1,3,null,{}]
05:15:33 INFO - 1500466533396 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
05:15:33 INFO - 1500466533396 Marionette TRACE 391 <- [1,4,null,{}]
05:15:33 INFO - 1500466533398 Marionette TRACE 391 -> [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}]
05:15:33 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500466533400 Marionette TRACE 391 <- [1,5,null,{"value":null}]
05:15:33 INFO - 1500466533400 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
05:15:33 INFO - 1500466533401 Marionette TRACE 391 <- [1,6,null,{}]
05:15:33 INFO - 1500466533403 Marionette DEBUG Closed connection 391
05:15:33 INFO - 1500466533404 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49820
05:15:33 INFO - 1500466533404 Marionette DEBUG Closed connection 392
05:15:33 INFO - 1500466533405 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49821
05:15:33 INFO - 1500466533406 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:33 INFO - 1500466533406 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533408 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533411 Marionette TRACE 393 <- [1,1,null,{"sessionId":"cc660875-7670-409f-8bea-874f83182ec7","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533412 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
05:15:33 INFO - 1500466533413 Marionette TRACE 393 <- [1,2,null,{}]
05:15:33 INFO - 1500466533416 Marionette DEBUG Closed connection 393
05:15:33 INFO - 1500466533416 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49822
05:15:33 INFO - 1500466533417 Marionette DEBUG Closed connection 394
05:15:33 INFO - 1500466533417 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49823
05:15:33 INFO - 1500466533418 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:15:33 INFO - 1500466533419 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533421 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533423 Marionette TRACE 395 <- [1,1,null,{"sessionId":"6f902eee-9e47-4510-9c48-249b5d05d7ca","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533425 Marionette TRACE 395 -> [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"}]
05:15:33 INFO - 1500466533426 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:15:33 INFO - 1500466533428 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:15:33 INFO - 1500466533428 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:15:33 INFO - 1500466533437 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"
05:15:33 INFO - 1500466533440 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"
05:15:33 INFO - 1500466533443 Marionette TRACE 395 <- [1,2,null,{}]
05:15:33 INFO - 1500466533449 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:15:33 INFO - 1500466533451 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1e0693c7-b633-48e7-916d-de9ef24e4608","ELEMENT":"1e0693c7-b633-48e7-916d-de9ef24e4608"}}]
05:15:33 INFO - 1500466533453 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:15:33 INFO - 1500466533454 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"edd78d68-7cff-4b15-8dec-ef5db6176a59","ELEMENT":"edd78d68-7cff-4b15-8dec-ef5db6176a59"}}]
05:15:33 INFO - 1500466533455 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"1e0693c7-b633-48e7-916d-de9ef24e4608"}]
05:15:33 INFO - 1500466533689 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:15:33 INFO - 1500466533690 Marionette TRACE 395 <- [1,5,null,{}]
05:15:33 INFO - 1500466533691 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"edd78d68-7cff-4b15-8dec-ef5db6176a59"}]
05:15:33 INFO - 1500466533710 Marionette TRACE 395 <- [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]
05:15:33 INFO - 1500466533713 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
05:15:33 INFO - 1500466533715 Marionette TRACE 395 <- [1,7,null,{"value":false}]
05:15:33 INFO - 1500466533716 Marionette TRACE 395 -> [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}]
05:15:33 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500466533718 Marionette TRACE 395 <- [1,8,null,{"value":null}]
05:15:33 INFO - 1500466533719 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
05:15:33 INFO - 1500466533720 Marionette TRACE 395 <- [1,9,null,{}]
05:15:33 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 339ms
05:15:33 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:15:33 INFO - 1500466533722 Marionette DEBUG Closed connection 395
05:15:33 INFO - 1500466533723 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49824
05:15:33 INFO - 1500466533724 Marionette DEBUG Closed connection 396
05:15:33 INFO - 1500466533724 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49825
05:15:33 INFO - 1500466533725 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:33 INFO - 1500466533726 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533728 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533730 Marionette TRACE 397 <- [1,1,null,{"sessionId":"8cabb38b-18bf-4090-ac5c-a6741a0f1cd5","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533732 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
05:15:33 INFO - 1500466533732 Marionette TRACE 397 <- [1,2,null,{}]
05:15:33 INFO - 1500466533733 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:15:33 INFO - 1500466533733 Marionette TRACE 397 <- [1,3,null,{}]
05:15:33 INFO - 1500466533734 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
05:15:33 INFO - 1500466533735 Marionette TRACE 397 <- [1,4,null,{}]
05:15:33 INFO - 1500466533736 Marionette TRACE 397 -> [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}]
05:15:33 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500466533738 Marionette TRACE 397 <- [1,5,null,{"value":null}]
05:15:33 INFO - 1500466533739 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
05:15:33 INFO - 1500466533740 Marionette TRACE 397 <- [1,6,null,{}]
05:15:33 INFO - 1500466533741 Marionette DEBUG Closed connection 397
05:15:33 INFO - 1500466533742 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49826
05:15:33 INFO - 1500466533742 Marionette DEBUG Closed connection 398
05:15:33 INFO - 1500466533743 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49827
05:15:33 INFO - 1500466533744 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:15:33 INFO - 1500466533744 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533746 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533749 Marionette TRACE 399 <- [1,1,null,{"sessionId":"75270c2b-4cc4-4df0-989f-0aba85ca5c01","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533751 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
05:15:33 INFO - 1500466533752 Marionette TRACE 399 <- [1,2,null,{}]
05:15:33 INFO - 1500466533753 Marionette DEBUG Closed connection 399
05:15:33 INFO - 1500466533754 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49828
05:15:33 INFO - 1500466533755 Marionette DEBUG Closed connection 400
05:15:33 INFO - 1500466533755 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49829
05:15:33 INFO - 1500466533756 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:15:33 INFO - 1500466533757 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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}
05:15:33 INFO - 1500466533758 Marionette DEBUG Register listener.js for window 15
05:15:33 INFO - 1500466533761 Marionette TRACE 401 <- [1,1,null,{"sessionId":"b95302e2-a4b9-4720-94b9-a1790bc653f9","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":3600,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-103.001\\AppData\\Local\\Temp\\tmpelpkpc.mozrunner","moz:accessibilityChecks":false}}]
05:15:33 INFO - 1500466533763 Marionette TRACE 401 -> [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"}]
05:15:33 INFO - 1500466533764 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"
05:15:33 INFO - 1500466533766 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"
05:15:33 INFO - 1500466533766 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"
05:15:33 INFO - 1500466533777 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"
05:15:33 INFO - 1500466533780 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"
05:15:33 INFO - 1500466533787 Marionette TRACE 401 <- [1,2,null,{}]
05:15:33 INFO - 1500466533789 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:15:33 INFO - 1500466533791 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"306cfd34-1185-4021-b2c1-77cead4b81ed","ELEMENT":"306cfd34-1185-4021-b2c1-77cead4b81ed"}}]
05:15:33 INFO - 1500466533792 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"306cfd34-1185-4021-b2c1-77cead4b81ed"}]
05:15:33 INFO - 1500466533815 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
05:15:33 INFO - 1500466533816 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"306cfd34-1185-4021-b2c1-77cead4b81ed"}]
05:15:33 INFO - 1500466533832 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element