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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:46:43 INFO - 1500698803847 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}]
21:46:43 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500698803849 Marionette TRACE 353 <- [1,5,null,{"value":null}]
21:46:43 INFO - 1500698803851 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
21:46:43 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 107ms
21:46:43 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:46:43 INFO - 1500698803852 Marionette TRACE 353 <- [1,6,null,{}]
21:46:43 INFO - 1500698803854 Marionette DEBUG Closed connection 353
21:46:43 INFO - 1500698803854 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49761
21:46:43 INFO - 1500698803855 Marionette DEBUG Closed connection 354
21:46:43 INFO - 1500698803855 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49762
21:46:43 INFO - 1500698803856 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:43 INFO - 1500698803857 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:43 INFO - 1500698803859 Marionette DEBUG Register listener.js for window 6442450945
21:46:43 INFO - 1500698803869 Marionette TRACE 355 <- [1,1,null,{"sessionId":"8b942e01-bf8e-4bfd-9497-53a1426b9c31","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:43 INFO - 1500698803870 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
21:46:43 INFO - 1500698803870 Marionette TRACE 355 <- [1,2,null,{}]
21:46:43 INFO - 1500698803871 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:46:43 INFO - 1500698803872 Marionette TRACE 355 <- [1,3,null,{}]
21:46:43 INFO - 1500698803872 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
21:46:43 INFO - 1500698803873 Marionette TRACE 355 <- [1,4,null,{}]
21:46:43 INFO - 1500698803874 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}]
21:46:43 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500698803877 Marionette TRACE 355 <- [1,5,null,{"value":null}]
21:46:43 INFO - 1500698803878 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
21:46:43 INFO - 1500698803879 Marionette TRACE 355 <- [1,6,null,{}]
21:46:43 INFO - 1500698803880 Marionette DEBUG Closed connection 355
21:46:43 INFO - 1500698803881 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49763
21:46:43 INFO - 1500698803882 Marionette DEBUG Closed connection 356
21:46:43 INFO - 1500698803882 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49764
21:46:43 INFO - 1500698803883 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:43 INFO - 1500698803883 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:43 INFO - 1500698803886 Marionette DEBUG Register listener.js for window 6442450945
21:46:43 INFO - 1500698803888 Marionette TRACE 357 <- [1,1,null,{"sessionId":"a0985ecf-4a02-4bb7-8952-6780b1aab124","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:43 INFO - 1500698803889 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
21:46:43 INFO - 1500698803890 Marionette TRACE 357 <- [1,2,null,{}]
21:46:43 INFO - 1500698803892 Marionette DEBUG Closed connection 357
21:46:43 INFO - 1500698803892 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49765
21:46:43 INFO - 1500698803893 Marionette DEBUG Closed connection 358
21:46:43 INFO - 1500698803894 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49766
21:46:43 INFO - 1500698803894 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:46:43 INFO - 1500698803895 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:43 INFO - 1500698803897 Marionette DEBUG Register listener.js for window 6442450945
21:46:43 INFO - 1500698803900 Marionette TRACE 359 <- [1,1,null,{"sessionId":"db2cfa6f-411b-4bfa-88bb-419f28c5787f","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:43 INFO - 1500698803901 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49324/clicks.html"}]
21:46:43 INFO - 1500698803902 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/hidden.html"
21:46:43 INFO - 1500698803909 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/hidden.html"
21:46:43 INFO - 1500698803909 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/hidden.html"
21:46:43 INFO - 1500698803915 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49324/clicks.html"
21:46:43 INFO - 1500698803919 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49324/clicks.html"
21:46:43 INFO - 1500698803928 Marionette TRACE 359 <- [1,2,null,{}]
21:46:43 INFO - 1500698803937 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:46:43 INFO - 1500698803939 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cbde4018-6907-4580-85bc-db522ac5bc98","ELEMENT":"cbde4018-6907-4580-85bc-db522ac5bc98"}}]
21:46:43 INFO - 1500698803940 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"cbde4018-6907-4580-85bc-db522ac5bc98"}]
21:46:43 INFO - 1500698803981 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/clicks.html"
21:46:43 INFO - 1500698803990 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/clicks.html"
21:46:43 INFO - 1500698803990 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/clicks.html"
21:46:43 INFO - 1500698803996 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49324/test.html"
21:46:44 INFO - 1500698803998 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49324/test.html"
21:46:44 INFO - 1500698804001 Marionette TRACE 359 <- [1,4,null,{}]
21:46:44 INFO - 1500698804007 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:46:44 INFO - 1500698804009 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b9ed187a-8e9f-418b-8a33-74d64c8e3f45","ELEMENT":"b9ed187a-8e9f-418b-8a33-74d64c8e3f45"}}]
21:46:44 INFO - 1500698804010 Marionette TRACE 359 -> [0,6,"getTitle",{}]
21:46:44 INFO - 1500698804011 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
21:46:44 INFO - 1500698804012 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}]
21:46:44 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500698804014 Marionette TRACE 359 <- [1,7,null,{"value":null}]
21:46:44 INFO - 1500698804015 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
21:46:44 INFO - 1500698804016 Marionette TRACE 359 <- [1,8,null,{}]
21:46:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 166ms
21:46:44 INFO - TEST-START | test_click.py TestClick.test_container_element
21:46:44 INFO - 1500698804021 Marionette DEBUG Closed connection 359
21:46:44 INFO - 1500698804021 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49769
21:46:44 INFO - 1500698804022 Marionette DEBUG Closed connection 360
21:46:44 INFO - 1500698804023 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49770
21:46:44 INFO - 1500698804023 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:44 INFO - 1500698804024 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804026 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804030 Marionette TRACE 361 <- [1,1,null,{"sessionId":"7e8df6a6-ebac-47ea-b384-c1c809bc1722","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804031 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
21:46:44 INFO - 1500698804031 Marionette TRACE 361 <- [1,2,null,{}]
21:46:44 INFO - 1500698804032 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:46:44 INFO - 1500698804032 Marionette TRACE 361 <- [1,3,null,{}]
21:46:44 INFO - 1500698804033 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
21:46:44 INFO - 1500698804034 Marionette TRACE 361 <- [1,4,null,{}]
21:46:44 INFO - 1500698804035 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}]
21:46:44 INFO - TEST-START: test_click.py TestClick.test_container_element1500698804038 Marionette TRACE 361 <- [1,5,null,{"value":null}]
21:46:44 INFO - 1500698804039 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
21:46:44 INFO - 1500698804040 Marionette TRACE 361 <- [1,6,null,{}]
21:46:44 INFO - 1500698804041 Marionette DEBUG Closed connection 361
21:46:44 INFO - 1500698804042 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49771
21:46:44 INFO - 1500698804043 Marionette DEBUG Closed connection 362
21:46:44 INFO - 1500698804043 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49772
21:46:44 INFO - 1500698804044 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:44 INFO - 1500698804044 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804047 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804049 Marionette TRACE 363 <- [1,1,null,{"sessionId":"a6588595-8f12-4259-a13b-6215f19eba56","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804050 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
21:46:44 INFO - 1500698804051 Marionette TRACE 363 <- [1,2,null,{}]
21:46:44 INFO - 1500698804053 Marionette DEBUG Closed connection 363
21:46:44 INFO - 1500698804053 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49773
21:46:44 INFO - 1500698804054 Marionette DEBUG Closed connection 364
21:46:44 INFO - 1500698804054 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49774
21:46:44 INFO - 1500698804055 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:46:44 INFO - 1500698804056 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804058 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804061 Marionette TRACE 365 <- [1,1,null,{"sessionId":"570598da-246c-43df-ac20-fb1a656343dc","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804062 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"}]
21:46:44 INFO - 1500698804064 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/test.html"
21:46:44 INFO - 1500698804065 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/test.html"
21:46:44 INFO - 1500698804066 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/test.html"
21:46:44 INFO - 1500698804071 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"
21:46:44 INFO - 1500698804073 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"
21:46:44 INFO - 1500698804079 Marionette TRACE 365 <- [1,2,null,{}]
21:46:44 INFO - 1500698804082 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:46:44 INFO - 1500698804088 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5ecc3109-615a-4c80-82eb-244ffcd43fde","ELEMENT":"5ecc3109-615a-4c80-82eb-244ffcd43fde"}}]
21:46:44 INFO - 1500698804089 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"5ecc3109-615a-4c80-82eb-244ffcd43fde"}]
21:46:44 INFO - 1500698804328 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:46:44 INFO - 1500698804329 Marionette TRACE 365 <- [1,4,null,{}]
21:46:44 INFO - 1500698804330 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"5ecc3109-615a-4c80-82eb-244ffcd43fde","name":"selected"}]
21:46:44 INFO - 1500698804335 Marionette TRACE 365 <- [1,5,null,{"value":true}]
21:46:44 INFO - 1500698804336 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}]
21:46:44 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 321ms
21:46:44 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:46:44 INFO - TEST-END: test_click.py TestClick.test_container_element1500698804339 Marionette TRACE 365 <- [1,6,null,{"value":null}]
21:46:44 INFO - 1500698804340 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
21:46:44 INFO - 1500698804341 Marionette TRACE 365 <- [1,7,null,{}]
21:46:44 INFO - 1500698804343 Marionette DEBUG Closed connection 365
21:46:44 INFO - 1500698804343 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49775
21:46:44 INFO - 1500698804344 Marionette DEBUG Closed connection 366
21:46:44 INFO - 1500698804345 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49776
21:46:44 INFO - 1500698804346 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:44 INFO - 1500698804346 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804348 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804351 Marionette TRACE 367 <- [1,1,null,{"sessionId":"447ae52e-0d75-4061-9065-1abba1391f02","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804353 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
21:46:44 INFO - 1500698804353 Marionette TRACE 367 <- [1,2,null,{}]
21:46:44 INFO - 1500698804354 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:46:44 INFO - 1500698804355 Marionette TRACE 367 <- [1,3,null,{}]
21:46:44 INFO - 1500698804355 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
21:46:44 INFO - 1500698804356 Marionette TRACE 367 <- [1,4,null,{}]
21:46:44 INFO - 1500698804357 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}]
21:46:44 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500698804360 Marionette TRACE 367 <- [1,5,null,{"value":null}]
21:46:44 INFO - 1500698804361 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
21:46:44 INFO - 1500698804362 Marionette TRACE 367 <- [1,6,null,{}]
21:46:44 INFO - 1500698804363 Marionette DEBUG Closed connection 367
21:46:44 INFO - 1500698804364 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49777
21:46:44 INFO - 1500698804364 Marionette DEBUG Closed connection 368
21:46:44 INFO - 1500698804365 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49778
21:46:44 INFO - 1500698804366 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:44 INFO - 1500698804367 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804369 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804372 Marionette TRACE 369 <- [1,1,null,{"sessionId":"9f9a25c3-ec0c-42b1-a2e3-77aee46d6865","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804373 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
21:46:44 INFO - 1500698804374 Marionette TRACE 369 <- [1,2,null,{}]
21:46:44 INFO - 1500698804375 Marionette DEBUG Closed connection 369
21:46:44 INFO - 1500698804376 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49779
21:46:44 INFO - 1500698804376 Marionette DEBUG Closed connection 370
21:46:44 INFO - 1500698804377 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49780
21:46:44 INFO - 1500698804378 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:46:44 INFO - 1500698804378 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804381 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804383 Marionette TRACE 371 <- [1,1,null,{"sessionId":"93c22cdc-7738-4076-8504-741601dc3413","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804384 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"}]
21:46:44 INFO - 1500698804386 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"
21:46:44 INFO - 1500698804388 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"
21:46:44 INFO - 1500698804388 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"
21:46:44 INFO - 1500698804395 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"
21:46:44 INFO - 1500698804399 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"
21:46:44 INFO - 1500698804404 Marionette TRACE 371 <- [1,2,null,{}]
21:46:44 INFO - 1500698804407 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:46:44 INFO - 1500698804409 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"afcc1ec9-540f-4873-84e7-7deb66112dee","ELEMENT":"afcc1ec9-540f-4873-84e7-7deb66112dee"}}]
21:46:44 INFO - 1500698804410 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"afcc1ec9-540f-4873-84e7-7deb66112dee"}]
21:46:44 INFO - 1500698804646 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:46:44 INFO - 1500698804647 Marionette TRACE 371 <- [1,4,null,{}]
21:46:44 INFO - 1500698804648 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"afcc1ec9-540f-4873-84e7-7deb66112dee","name":"selected"}]
21:46:44 INFO - 1500698804652 Marionette TRACE 371 <- [1,5,null,{"value":true}]
21:46:44 INFO - 1500698804654 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}]
21:46:44 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500698804656 Marionette TRACE 371 <- [1,6,null,{"value":null}]
21:46:44 INFO - 1500698804657 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
21:46:44 INFO - 1500698804658 Marionette TRACE 371 <- [1,7,null,{}]
21:46:44 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 318ms
21:46:44 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:46:44 INFO - 1500698804660 Marionette DEBUG Closed connection 371
21:46:44 INFO - 1500698804661 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49781
21:46:44 INFO - 1500698804661 Marionette DEBUG Closed connection 372
21:46:44 INFO - 1500698804662 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49782
21:46:44 INFO - 1500698804663 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:44 INFO - 1500698804663 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804666 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804669 Marionette TRACE 373 <- [1,1,null,{"sessionId":"a2d08adf-da10-4885-b19e-7a0ecc1c1be2","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804670 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
21:46:44 INFO - 1500698804670 Marionette TRACE 373 <- [1,2,null,{}]
21:46:44 INFO - 1500698804671 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:46:44 INFO - 1500698804671 Marionette TRACE 373 <- [1,3,null,{}]
21:46:44 INFO - 1500698804672 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
21:46:44 INFO - 1500698804672 Marionette TRACE 373 <- [1,4,null,{}]
21:46:44 INFO - 1500698804674 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}]
21:46:44 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500698804677 Marionette TRACE 373 <- [1,5,null,{"value":null}]
21:46:44 INFO - 1500698804677 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
21:46:44 INFO - 1500698804679 Marionette TRACE 373 <- [1,6,null,{}]
21:46:44 INFO - 1500698804680 Marionette DEBUG Closed connection 373
21:46:44 INFO - 1500698804680 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49783
21:46:44 INFO - 1500698804681 Marionette DEBUG Closed connection 374
21:46:44 INFO - 1500698804682 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49784
21:46:44 INFO - 1500698804682 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:44 INFO - 1500698804683 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804685 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804688 Marionette TRACE 375 <- [1,1,null,{"sessionId":"40ac3a90-acf6-47c8-9e41-662f6b1abe85","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804689 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
21:46:44 INFO - 1500698804690 Marionette TRACE 375 <- [1,2,null,{}]
21:46:44 INFO - 1500698804691 Marionette DEBUG Closed connection 375
21:46:44 INFO - 1500698804692 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49785
21:46:44 INFO - 1500698804693 Marionette DEBUG Closed connection 376
21:46:44 INFO - 1500698804693 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49786
21:46:44 INFO - 1500698804694 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:46:44 INFO - 1500698804695 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804697 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804699 Marionette TRACE 377 <- [1,1,null,{"sessionId":"fc21d71e-23fb-42fc-a162-66a064f611bd","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804700 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"}]
21:46:44 INFO - 1500698804702 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"
21:46:44 INFO - 1500698804703 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"
21:46:44 INFO - 1500698804704 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"
21:46:44 INFO - 1500698804709 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"
21:46:44 INFO - 1500698804711 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"
21:46:44 INFO - 1500698804716 Marionette TRACE 377 <- [1,2,null,{}]
21:46:44 INFO - 1500698804724 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:46:44 INFO - 1500698804727 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"696afb53-e2c6-4b1e-b3ec-3e3824c1c900","ELEMENT":"696afb53-e2c6-4b1e-b3ec-3e3824c1c900"}}]
21:46:44 INFO - 1500698804728 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"696afb53-e2c6-4b1e-b3ec-3e3824c1c900"}]
21:46:44 INFO - 1500698804967 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:46:44 INFO - 1500698804968 Marionette TRACE 377 <- [1,4,null,{}]
21:46:44 INFO - 1500698804970 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}]
21:46:44 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500698804972 Marionette TRACE 377 <- [1,5,null,{"value":null}]
21:46:44 INFO - 1500698804973 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
21:46:44 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 316ms
21:46:44 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:46:44 INFO - 1500698804975 Marionette TRACE 377 <- [1,6,null,{}]
21:46:44 INFO - 1500698804976 Marionette DEBUG Closed connection 377
21:46:44 INFO - 1500698804977 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49787
21:46:44 INFO - 1500698804978 Marionette DEBUG Closed connection 378
21:46:44 INFO - 1500698804979 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49788
21:46:44 INFO - 1500698804979 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:44 INFO - 1500698804981 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:44 INFO - 1500698804983 Marionette DEBUG Register listener.js for window 6442450945
21:46:44 INFO - 1500698804986 Marionette TRACE 379 <- [1,1,null,{"sessionId":"6747b822-66ae-4cac-9958-62bc41694132","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:44 INFO - 1500698804986 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
21:46:44 INFO - 1500698804987 Marionette TRACE 379 <- [1,2,null,{}]
21:46:44 INFO - 1500698804988 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:46:44 INFO - 1500698804988 Marionette TRACE 379 <- [1,3,null,{}]
21:46:44 INFO - 1500698804989 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
21:46:44 INFO - 1500698804990 Marionette TRACE 379 <- [1,4,null,{}]
21:46:44 INFO - 1500698804991 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}]
21:46:44 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500698804994 Marionette TRACE 379 <- [1,5,null,{"value":null}]
21:46:44 INFO - 1500698804994 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
21:46:44 INFO - 1500698804996 Marionette TRACE 379 <- [1,6,null,{}]
21:46:44 INFO - 1500698804997 Marionette DEBUG Closed connection 379
21:46:44 INFO - 1500698804997 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49789
21:46:45 INFO - 1500698804998 Marionette DEBUG Closed connection 380
21:46:45 INFO - 1500698804999 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49790
21:46:45 INFO - 1500698805000 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:45 INFO - 1500698805000 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805002 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805005 Marionette TRACE 381 <- [1,1,null,{"sessionId":"1c7dde6b-ab78-4b2c-985a-2606e58ea1f4","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805007 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
21:46:45 INFO - 1500698805008 Marionette TRACE 381 <- [1,2,null,{}]
21:46:45 INFO - 1500698805009 Marionette DEBUG Closed connection 381
21:46:45 INFO - 1500698805009 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49791
21:46:45 INFO - 1500698805010 Marionette DEBUG Closed connection 382
21:46:45 INFO - 1500698805010 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49792
21:46:45 INFO - 1500698805011 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:46:45 INFO - 1500698805012 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805014 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805024 Marionette TRACE 383 <- [1,1,null,{"sessionId":"687d430b-f879-42a4-ba4e-941fa9f7cea8","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805025 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"}]
21:46:45 INFO - 1500698805027 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"
21:46:45 INFO - 1500698805029 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"
21:46:45 INFO - 1500698805029 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"
21:46:45 INFO - 1500698805035 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"
21:46:45 INFO - 1500698805037 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"
21:46:45 INFO - 1500698805043 Marionette TRACE 383 <- [1,2,null,{}]
21:46:45 INFO - 1500698805045 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:46:45 INFO - 1500698805047 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"06e7aaf5-f541-455c-84f4-16e8edc09c80","ELEMENT":"06e7aaf5-f541-455c-84f4-16e8edc09c80"}}]
21:46:45 INFO - 1500698805048 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"06e7aaf5-f541-455c-84f4-16e8edc09c80"}]
21:46:45 INFO - 1500698805285 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:46:45 INFO - 1500698805286 Marionette TRACE 383 <- [1,4,null,{}]
21:46:45 INFO - 1500698805288 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}]
21:46:45 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500698805290 Marionette TRACE 383 <- [1,5,null,{"value":null}]
21:46:45 INFO - 1500698805291 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
21:46:45 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 317ms
21:46:45 INFO - TEST-START | test_click.py TestClick.test_input_file
21:46:45 INFO - 1500698805292 Marionette TRACE 383 <- [1,6,null,{}]
21:46:45 INFO - 1500698805294 Marionette DEBUG Closed connection 383
21:46:45 INFO - 1500698805295 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49793
21:46:45 INFO - 1500698805295 Marionette DEBUG Closed connection 384
21:46:45 INFO - 1500698805296 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49794
21:46:45 INFO - 1500698805298 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:45 INFO - 1500698805298 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805300 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805303 Marionette TRACE 385 <- [1,1,null,{"sessionId":"95b79607-1e33-4746-8b4b-f2f302e841cd","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805304 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
21:46:45 INFO - 1500698805305 Marionette TRACE 385 <- [1,2,null,{}]
21:46:45 INFO - 1500698805305 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:46:45 INFO - 1500698805306 Marionette TRACE 385 <- [1,3,null,{}]
21:46:45 INFO - 1500698805307 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
21:46:45 INFO - 1500698805307 Marionette TRACE 385 <- [1,4,null,{}]
21:46:45 INFO - 1500698805308 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}]
21:46:45 INFO - TEST-START: test_click.py TestClick.test_input_file1500698805311 Marionette TRACE 385 <- [1,5,null,{"value":null}]
21:46:45 INFO - 1500698805311 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
21:46:45 INFO - 1500698805313 Marionette TRACE 385 <- [1,6,null,{}]
21:46:45 INFO - 1500698805314 Marionette DEBUG Closed connection 385
21:46:45 INFO - 1500698805314 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49795
21:46:45 INFO - 1500698805315 Marionette DEBUG Closed connection 386
21:46:45 INFO - 1500698805316 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49796
21:46:45 INFO - 1500698805317 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:45 INFO - 1500698805317 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805319 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805322 Marionette TRACE 387 <- [1,1,null,{"sessionId":"7b84cb3f-da35-430a-b072-cfcb2cebdc34","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805323 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
21:46:45 INFO - 1500698805324 Marionette TRACE 387 <- [1,2,null,{}]
21:46:45 INFO - 1500698805325 Marionette DEBUG Closed connection 387
21:46:45 INFO - 1500698805326 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49797
21:46:45 INFO - 1500698805326 Marionette DEBUG Closed connection 388
21:46:45 INFO - 1500698805327 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49798
21:46:45 INFO - 1500698805328 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:46:45 INFO - 1500698805328 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805330 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805333 Marionette TRACE 389 <- [1,1,null,{"sessionId":"f58c74a9-e9f3-4fab-a8d1-46ed496c5206","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805334 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:46:45 INFO - 1500698805336 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"
21:46:45 INFO - 1500698805337 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"
21:46:45 INFO - 1500698805338 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"
21:46:45 INFO - 1500698805343 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:46:45 INFO - 1500698805346 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:46:45 INFO - 1500698805349 Marionette TRACE 389 <- [1,2,null,{}]
21:46:45 INFO - 1500698805356 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:46:45 INFO - 1500698805358 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8733fdd3-0b18-4036-abf0-302ca9e48adc","ELEMENT":"8733fdd3-0b18-4036-abf0-302ca9e48adc"}}]
21:46:45 INFO - 1500698805359 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"8733fdd3-0b18-4036-abf0-302ca9e48adc"}]
21:46:45 INFO - 1500698805389 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:46:45 INFO - 1500698805391 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}]
21:46:45 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 102ms
21:46:45 INFO - TEST-END: test_click.py TestClick.test_input_file1500698805393 Marionette TRACE 389 <- [1,5,null,{"value":null}]
21:46:45 INFO - 1500698805394 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
21:46:45 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:46:45 INFO - 1500698805395 Marionette TRACE 389 <- [1,6,null,{}]
21:46:45 INFO - 1500698805397 Marionette DEBUG Closed connection 389
21:46:45 INFO - 1500698805398 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49799
21:46:45 INFO - 1500698805399 Marionette DEBUG Closed connection 390
21:46:45 INFO - 1500698805399 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49800
21:46:45 INFO - 1500698805400 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:45 INFO - 1500698805401 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805403 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805406 Marionette TRACE 391 <- [1,1,null,{"sessionId":"886a821e-e93c-429b-bf7e-36d76b1ab14b","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805407 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
21:46:45 INFO - 1500698805407 Marionette TRACE 391 <- [1,2,null,{}]
21:46:45 INFO - 1500698805408 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:46:45 INFO - 1500698805409 Marionette TRACE 391 <- [1,3,null,{}]
21:46:45 INFO - 1500698805409 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
21:46:45 INFO - 1500698805410 Marionette TRACE 391 <- [1,4,null,{}]
21:46:45 INFO - 1500698805412 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}]
21:46:45 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500698805414 Marionette TRACE 391 <- [1,5,null,{"value":null}]
21:46:45 INFO - 1500698805415 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
21:46:45 INFO - 1500698805416 Marionette TRACE 391 <- [1,6,null,{}]
21:46:45 INFO - 1500698805418 Marionette DEBUG Closed connection 391
21:46:45 INFO - 1500698805418 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49801
21:46:45 INFO - 1500698805419 Marionette DEBUG Closed connection 392
21:46:45 INFO - 1500698805419 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49802
21:46:45 INFO - 1500698805420 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:45 INFO - 1500698805421 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805423 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805426 Marionette TRACE 393 <- [1,1,null,{"sessionId":"37860b82-939c-4f7b-a6ff-97f447dcb98f","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805427 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
21:46:45 INFO - 1500698805428 Marionette TRACE 393 <- [1,2,null,{}]
21:46:45 INFO - 1500698805429 Marionette DEBUG Closed connection 393
21:46:45 INFO - 1500698805430 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49803
21:46:45 INFO - 1500698805431 Marionette DEBUG Closed connection 394
21:46:45 INFO - 1500698805431 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49804
21:46:45 INFO - 1500698805432 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:46:45 INFO - 1500698805433 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805435 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805438 Marionette TRACE 395 <- [1,1,null,{"sessionId":"97d61faf-68ba-48b0-9fed-25c0ac00e804","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805439 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"}]
21:46:45 INFO - 1500698805441 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:46:45 INFO - 1500698805442 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:46:45 INFO - 1500698805442 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:46:45 INFO - 1500698805448 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"
21:46:45 INFO - 1500698805450 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"
21:46:45 INFO - 1500698805456 Marionette TRACE 395 <- [1,2,null,{}]
21:46:45 INFO - 1500698805459 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:46:45 INFO - 1500698805461 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6b91075a-bf78-4a1d-9f6d-7e076649b095","ELEMENT":"6b91075a-bf78-4a1d-9f6d-7e076649b095"}}]
21:46:45 INFO - 1500698805462 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:46:45 INFO - 1500698805463 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a92197f7-2d1d-4395-a95a-c46f19d97845","ELEMENT":"a92197f7-2d1d-4395-a95a-c46f19d97845"}}]
21:46:45 INFO - 1500698805464 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"6b91075a-bf78-4a1d-9f6d-7e076649b095"}]
21:46:45 INFO - 1500698805699 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:46:45 INFO - 1500698805700 Marionette TRACE 395 <- [1,5,null,{}]
21:46:45 INFO - 1500698805701 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"a92197f7-2d1d-4395-a95a-c46f19d97845"}]
21:46:45 INFO - 1500698805716 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:46:45 INFO - 1500698805718 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:46:45 INFO - 1500698805721 Marionette TRACE 395 <- [1,7,null,{"value":false}]
21:46:45 INFO - 1500698805722 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}]
21:46:45 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500698805725 Marionette TRACE 395 <- [1,8,null,{"value":null}]
21:46:45 INFO - 1500698805725 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
21:46:45 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 331ms
21:46:45 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:46:45 INFO - 1500698805727 Marionette TRACE 395 <- [1,9,null,{}]
21:46:45 INFO - 1500698805728 Marionette DEBUG Closed connection 395
21:46:45 INFO - 1500698805729 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49805
21:46:45 INFO - 1500698805730 Marionette DEBUG Closed connection 396
21:46:45 INFO - 1500698805730 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49806
21:46:45 INFO - 1500698805731 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:45 INFO - 1500698805732 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805734 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805737 Marionette TRACE 397 <- [1,1,null,{"sessionId":"4874eade-78f4-4e5e-bc53-2363ef7d2ba2","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805738 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
21:46:45 INFO - 1500698805739 Marionette TRACE 397 <- [1,2,null,{}]
21:46:45 INFO - 1500698805739 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:46:45 INFO - 1500698805740 Marionette TRACE 397 <- [1,3,null,{}]
21:46:45 INFO - 1500698805741 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
21:46:45 INFO - 1500698805741 Marionette TRACE 397 <- [1,4,null,{}]
21:46:45 INFO - 1500698805742 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}]
21:46:45 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500698805745 Marionette TRACE 397 <- [1,5,null,{"value":null}]
21:46:45 INFO - 1500698805746 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
21:46:45 INFO - 1500698805747 Marionette TRACE 397 <- [1,6,null,{}]
21:46:45 INFO - 1500698805748 Marionette DEBUG Closed connection 397
21:46:45 INFO - 1500698805749 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49807
21:46:45 INFO - 1500698805750 Marionette DEBUG Closed connection 398
21:46:45 INFO - 1500698805750 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49808
21:46:45 INFO - 1500698805751 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:46:45 INFO - 1500698805752 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805754 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805757 Marionette TRACE 399 <- [1,1,null,{"sessionId":"ef80d555-6f3f-4cd4-8274-bc88fb0d2203","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805758 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
21:46:45 INFO - 1500698805759 Marionette TRACE 399 <- [1,2,null,{}]
21:46:45 INFO - 1500698805761 Marionette DEBUG Closed connection 399
21:46:45 INFO - 1500698805761 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49809
21:46:45 INFO - 1500698805762 Marionette DEBUG Closed connection 400
21:46:45 INFO - 1500698805762 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49810
21:46:45 INFO - 1500698805763 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:46:45 INFO - 1500698805764 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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}
21:46:45 INFO - 1500698805766 Marionette DEBUG Register listener.js for window 6442450945
21:46:45 INFO - 1500698805769 Marionette TRACE 401 <- [1,1,null,{"sessionId":"bcd9c6c0-d136-461e-9df8-cd07db995fde","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":3280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpv9pthq.mozrunner","moz:accessibilityChecks":false}}]
21:46:45 INFO - 1500698805770 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"}]
21:46:45 INFO - 1500698805772 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"
21:46:45 INFO - 1500698805773 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"
21:46:45 INFO - 1500698805774 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"
21:46:45 INFO - 1500698805780 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"
21:46:45 INFO - 1500698805782 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"
21:46:45 INFO - 1500698805786 Marionette TRACE 401 <- [1,2,null,{}]
21:46:45 INFO - 1500698805793 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:46:45 INFO - 1500698805795 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a097d2f9-9ecd-476f-ac0b-d131d26cb86a","ELEMENT":"a097d2f9-9ecd-476f-ac0b-d131d26cb86a"}}]
21:46:45 INFO - 1500698805796 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a097d2f9-9ecd-476f-ac0b-d131d26cb86a"}]
21:46:45 INFO - 1500698805822 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
21:46:45 INFO - 1500698805823 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"a097d2f9-9ecd-476f-ac0b-d131d26cb86a"}]
21:46:45 INFO - 1500698805836 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element