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]
06:53:19 INFO - 1501077199940 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
06:53:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501077199943 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:53:19 INFO - 1501077199944 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:53:19 INFO - 1501077199945 Marionette TRACE 352 <- [1,6,null,{}]
06:53:19 INFO - 1501077199947 Marionette DEBUG Closed connection 352
06:53:19 INFO - 1501077199948 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49743
06:53:19 INFO - 1501077199949 Marionette DEBUG Closed connection 353
06:53:19 INFO - 1501077199949 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49744
06:53:19 INFO - 1501077199950 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:19 INFO - 1501077199951 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:19 INFO - 1501077199952 Marionette DEBUG Register listener.js for window 12
06:53:19 INFO - 1501077199955 Marionette TRACE 354 <- [1,1,null,{"sessionId":"3dedc496-ed9b-4155-82ac-a4c42afec7d0","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:19 INFO - 1501077199957 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:53:19 INFO - 1501077199958 Marionette TRACE 354 <- [1,2,null,{}]
06:53:19 INFO - 1501077199959 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:19 INFO - 1501077199959 Marionette TRACE 354 <- [1,3,null,{}]
06:53:19 INFO - 1501077199960 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:19 INFO - 1501077199960 Marionette TRACE 354 <- [1,4,null,{}]
06:53:19 INFO - 1501077199962 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
06:53:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501077199965 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:53:19 INFO - 1501077199965 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:53:19 INFO - 1501077199966 Marionette TRACE 354 <- [1,6,null,{}]
06:53:19 INFO - 1501077199968 Marionette DEBUG Closed connection 354
06:53:19 INFO - 1501077199969 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49745
06:53:19 INFO - 1501077199969 Marionette DEBUG Closed connection 355
06:53:19 INFO - 1501077199970 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49746
06:53:19 INFO - 1501077199971 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:19 INFO - 1501077199971 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:19 INFO - 1501077199973 Marionette DEBUG Register listener.js for window 12
06:53:19 INFO - 1501077199975 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5d009a2e-1ea2-4115-b675-ecdf20f62ab4","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:19 INFO - 1501077199977 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:53:19 INFO - 1501077199978 Marionette TRACE 356 <- [1,2,null,{}]
06:53:19 INFO - 1501077199980 Marionette DEBUG Closed connection 356
06:53:19 INFO - 1501077199980 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49747
06:53:19 INFO - 1501077199981 Marionette DEBUG Closed connection 357
06:53:19 INFO - 1501077199981 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49748
06:53:19 INFO - 1501077199982 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:19 INFO - 1501077199983 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:19 INFO - 1501077199984 Marionette DEBUG Register listener.js for window 12
06:53:19 INFO - 1501077199987 Marionette TRACE 358 <- [1,1,null,{"sessionId":"e9f74072-278b-414a-9068-763a99bcd4d9","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:19 INFO - 1501077199989 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49319/clicks.html"}]
06:53:19 INFO - 1501077199990 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49319/hidden.html"
06:53:20 INFO - 1501077199997 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49319/hidden.html"
06:53:20 INFO - 1501077199998 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49319/hidden.html"
06:53:20 INFO - 1501077200008 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49319/clicks.html"
06:53:20 INFO - 1501077200015 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49319/clicks.html"
06:53:20 INFO - 1501077200019 Marionette TRACE 358 <- [1,2,null,{}]
06:53:20 INFO - 1501077200021 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:53:20 INFO - 1501077200023 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93fb7c23-b614-4220-a46a-192ea7412911","ELEMENT":"93fb7c23-b614-4220-a46a-192ea7412911"}}]
06:53:20 INFO - 1501077200024 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"93fb7c23-b614-4220-a46a-192ea7412911"}]
06:53:20 INFO - 1501077200068 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49319/clicks.html"
06:53:20 INFO - 1501077200073 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49319/clicks.html"
06:53:20 INFO - 1501077200073 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49319/clicks.html"
06:53:20 INFO - 1501077200089 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49319/test.html"
06:53:20 INFO - 1501077200092 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49319/test.html"
06:53:20 INFO - 1501077200099 Marionette TRACE 358 <- [1,4,null,{}]
06:53:20 INFO - 1501077200103 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:53:20 INFO - 1501077200104 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf20cca3-4791-4956-bf80-304bdebf345d","ELEMENT":"cf20cca3-4791-4956-bf80-304bdebf345d"}}]
06:53:20 INFO - 1501077200105 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:53:20 INFO - 1501077200106 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:53:20 INFO - 1501077200107 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
06:53:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501077200110 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:53:20 INFO - 1501077200111 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:53:20 INFO - 1501077200112 Marionette TRACE 358 <- [1,8,null,{}]
06:53:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 166ms
06:53:20 INFO - TEST-START | test_click.py TestClick.test_container_element
06:53:20 INFO - 1501077200114 Marionette DEBUG Closed connection 358
06:53:20 INFO - 1501077200124 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49751
06:53:20 INFO - 1501077200125 Marionette DEBUG Closed connection 359
06:53:20 INFO - 1501077200126 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49752
06:53:20 INFO - 1501077200127 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:20 INFO - 1501077200127 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200129 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200131 Marionette TRACE 360 <- [1,1,null,{"sessionId":"a08952e4-66c6-40aa-a032-b0b961e33a47","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200141 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:53:20 INFO - 1501077200141 Marionette TRACE 360 <- [1,2,null,{}]
06:53:20 INFO - 1501077200142 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:20 INFO - 1501077200142 Marionette TRACE 360 <- [1,3,null,{}]
06:53:20 INFO - 1501077200143 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:20 INFO - 1501077200143 Marionette TRACE 360 <- [1,4,null,{}]
06:53:20 INFO - 1501077200145 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
06:53:20 INFO - TEST-START: test_click.py TestClick.test_container_element1501077200147 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:53:20 INFO - 1501077200148 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:53:20 INFO - 1501077200149 Marionette TRACE 360 <- [1,6,null,{}]
06:53:20 INFO - 1501077200151 Marionette DEBUG Closed connection 360
06:53:20 INFO - 1501077200151 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49753
06:53:20 INFO - 1501077200152 Marionette DEBUG Closed connection 361
06:53:20 INFO - 1501077200152 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49754
06:53:20 INFO - 1501077200153 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:20 INFO - 1501077200154 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200155 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200159 Marionette TRACE 362 <- [1,1,null,{"sessionId":"51b0cd88-14aa-4448-b470-d4d8087e515b","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200161 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:53:20 INFO - 1501077200162 Marionette TRACE 362 <- [1,2,null,{}]
06:53:20 INFO - 1501077200163 Marionette DEBUG Closed connection 362
06:53:20 INFO - 1501077200164 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49755
06:53:20 INFO - 1501077200164 Marionette DEBUG Closed connection 363
06:53:20 INFO - 1501077200165 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49756
06:53:20 INFO - 1501077200166 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:20 INFO - 1501077200166 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200168 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200170 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0156e4ed-f9ed-4238-aa86-179b1f773385","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200172 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:53:20 INFO - 1501077200173 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49319/test.html"
06:53:20 INFO - 1501077200175 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49319/test.html"
06:53:20 INFO - 1501077200175 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49319/test.html"
06:53:20 INFO - 1501077200197 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"
06:53:20 INFO - 1501077200198 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"
06:53:20 INFO - 1501077200201 Marionette TRACE 364 <- [1,2,null,{}]
06:53:20 INFO - 1501077200202 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:20 INFO - 1501077200204 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b7e63fcd-0c43-4fe5-983c-200d223143fa","ELEMENT":"b7e63fcd-0c43-4fe5-983c-200d223143fa"}}]
06:53:20 INFO - 1501077200207 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b7e63fcd-0c43-4fe5-983c-200d223143fa"}]
06:53:20 INFO - 1501077200445 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:20 INFO - 1501077200446 Marionette TRACE 364 <- [1,4,null,{}]
06:53:20 INFO - 1501077200447 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b7e63fcd-0c43-4fe5-983c-200d223143fa","name":"selected"}]
06:53:20 INFO - 1501077200451 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:53:20 INFO - 1501077200452 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
06:53:20 INFO - TEST-END: test_click.py TestClick.test_container_element1501077200454 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:53:20 INFO - 1501077200455 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:53:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 344ms
06:53:20 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:53:20 INFO - 1501077200456 Marionette TRACE 364 <- [1,7,null,{}]
06:53:20 INFO - 1501077200458 Marionette DEBUG Closed connection 364
06:53:20 INFO - 1501077200459 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49757
06:53:20 INFO - 1501077200460 Marionette DEBUG Closed connection 365
06:53:20 INFO - 1501077200460 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49758
06:53:20 INFO - 1501077200461 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:20 INFO - 1501077200461 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200463 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200465 Marionette TRACE 366 <- [1,1,null,{"sessionId":"7c196c09-c3e3-4569-88b8-483d5c49d5f6","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200467 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:53:20 INFO - 1501077200468 Marionette TRACE 366 <- [1,2,null,{}]
06:53:20 INFO - 1501077200468 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:20 INFO - 1501077200469 Marionette TRACE 366 <- [1,3,null,{}]
06:53:20 INFO - 1501077200470 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:20 INFO - 1501077200471 Marionette TRACE 366 <- [1,4,null,{}]
06:53:20 INFO - 1501077200472 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
06:53:20 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501077200474 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:53:20 INFO - 1501077200476 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:53:20 INFO - 1501077200478 Marionette TRACE 366 <- [1,6,null,{}]
06:53:20 INFO - 1501077200479 Marionette DEBUG Closed connection 366
06:53:20 INFO - 1501077200480 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49759
06:53:20 INFO - 1501077200480 Marionette DEBUG Closed connection 367
06:53:20 INFO - 1501077200481 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49760
06:53:20 INFO - 1501077200482 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:20 INFO - 1501077200483 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200484 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200487 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e433be94-6d1e-4d85-892a-47bc7bc458a9","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200489 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:53:20 INFO - 1501077200490 Marionette TRACE 368 <- [1,2,null,{}]
06:53:20 INFO - 1501077200492 Marionette DEBUG Closed connection 368
06:53:20 INFO - 1501077200492 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49761
06:53:20 INFO - 1501077200493 Marionette DEBUG Closed connection 369
06:53:20 INFO - 1501077200494 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49762
06:53:20 INFO - 1501077200495 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:20 INFO - 1501077200495 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200497 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200506 Marionette TRACE 370 <- [1,1,null,{"sessionId":"4bdb59d6-22cd-4d37-861f-933e1d76b88a","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200508 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:53:20 INFO - 1501077200509 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"
06:53:20 INFO - 1501077200511 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"
06:53:20 INFO - 1501077200511 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"
06:53:20 INFO - 1501077200520 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"
06:53:20 INFO - 1501077200523 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"
06:53:20 INFO - 1501077200526 Marionette TRACE 370 <- [1,2,null,{}]
06:53:20 INFO - 1501077200535 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:53:20 INFO - 1501077200537 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d270293b-b474-4f69-bd83-a868bb316843","ELEMENT":"d270293b-b474-4f69-bd83-a868bb316843"}}]
06:53:20 INFO - 1501077200538 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d270293b-b474-4f69-bd83-a868bb316843"}]
06:53:20 INFO - 1501077200774 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:20 INFO - 1501077200775 Marionette TRACE 370 <- [1,4,null,{}]
06:53:20 INFO - 1501077200776 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d270293b-b474-4f69-bd83-a868bb316843","name":"selected"}]
06:53:20 INFO - 1501077200780 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:53:20 INFO - 1501077200781 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
06:53:20 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501077200783 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:53:20 INFO - 1501077200784 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:53:20 INFO - 1501077200785 Marionette TRACE 370 <- [1,7,null,{}]
06:53:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 329ms
06:53:20 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:53:20 INFO - 1501077200787 Marionette DEBUG Closed connection 370
06:53:20 INFO - 1501077200788 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49763
06:53:20 INFO - 1501077200789 Marionette DEBUG Closed connection 371
06:53:20 INFO - 1501077200790 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49764
06:53:20 INFO - 1501077200791 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:20 INFO - 1501077200791 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200793 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200795 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2b39a78a-5be7-4043-84b2-c0d72e004c90","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200797 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:53:20 INFO - 1501077200798 Marionette TRACE 372 <- [1,2,null,{}]
06:53:20 INFO - 1501077200799 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:20 INFO - 1501077200799 Marionette TRACE 372 <- [1,3,null,{}]
06:53:20 INFO - 1501077200800 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:20 INFO - 1501077200800 Marionette TRACE 372 <- [1,4,null,{}]
06:53:20 INFO - 1501077200802 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
06:53:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501077200803 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:53:20 INFO - 1501077200804 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:53:20 INFO - 1501077200805 Marionette TRACE 372 <- [1,6,null,{}]
06:53:20 INFO - 1501077200807 Marionette DEBUG Closed connection 372
06:53:20 INFO - 1501077200807 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49765
06:53:20 INFO - 1501077200808 Marionette DEBUG Closed connection 373
06:53:20 INFO - 1501077200809 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49766
06:53:20 INFO - 1501077200810 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:20 INFO - 1501077200810 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200812 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200814 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c5aad328-d7f6-4536-910e-33301c2ae33f","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200816 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:53:20 INFO - 1501077200817 Marionette TRACE 374 <- [1,2,null,{}]
06:53:20 INFO - 1501077200818 Marionette DEBUG Closed connection 374
06:53:20 INFO - 1501077200819 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49767
06:53:20 INFO - 1501077200819 Marionette DEBUG Closed connection 375
06:53:20 INFO - 1501077200820 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49768
06:53:20 INFO - 1501077200821 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:20 INFO - 1501077200821 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:20 INFO - 1501077200823 Marionette DEBUG Register listener.js for window 12
06:53:20 INFO - 1501077200825 Marionette TRACE 376 <- [1,1,null,{"sessionId":"61156ddb-19cf-42e3-92c6-67a85078396f","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:20 INFO - 1501077200827 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
06:53:20 INFO - 1501077200828 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"
06:53:20 INFO - 1501077200830 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"
06:53:20 INFO - 1501077200830 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"
06:53:20 INFO - 1501077200840 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"
06:53:20 INFO - 1501077200843 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"
06:53:20 INFO - 1501077200851 Marionette TRACE 376 <- [1,2,null,{}]
06:53:20 INFO - 1501077200853 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:53:20 INFO - 1501077200855 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c372a6c-79fc-4d0b-bf4e-8eb60b322712","ELEMENT":"3c372a6c-79fc-4d0b-bf4e-8eb60b322712"}}]
06:53:20 INFO - 1501077200856 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"3c372a6c-79fc-4d0b-bf4e-8eb60b322712"}]
06:53:21 INFO - 1501077201096 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:21 INFO - 1501077201097 Marionette TRACE 376 <- [1,4,null,{}]
06:53:21 INFO - 1501077201099 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
06:53:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501077201101 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:53:21 INFO - 1501077201102 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:53:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 317ms
06:53:21 INFO - 1501077201103 Marionette TRACE 376 <- [1,6,null,{}]
06:53:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:53:21 INFO - 1501077201105 Marionette DEBUG Closed connection 376
06:53:21 INFO - 1501077201106 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49769
06:53:21 INFO - 1501077201106 Marionette DEBUG Closed connection 377
06:53:21 INFO - 1501077201107 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49770
06:53:21 INFO - 1501077201108 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:21 INFO - 1501077201109 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201110 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201114 Marionette TRACE 378 <- [1,1,null,{"sessionId":"cc622b3a-7e15-46da-8fb9-aa394ba9cb51","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201116 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:53:21 INFO - 1501077201116 Marionette TRACE 378 <- [1,2,null,{}]
06:53:21 INFO - 1501077201117 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:21 INFO - 1501077201117 Marionette TRACE 378 <- [1,3,null,{}]
06:53:21 INFO - 1501077201118 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:21 INFO - 1501077201118 Marionette TRACE 378 <- [1,4,null,{}]
06:53:21 INFO - 1501077201121 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
06:53:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501077201123 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:53:21 INFO - 1501077201124 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:53:21 INFO - 1501077201125 Marionette TRACE 378 <- [1,6,null,{}]
06:53:21 INFO - 1501077201126 Marionette DEBUG Closed connection 378
06:53:21 INFO - 1501077201127 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49771
06:53:21 INFO - 1501077201128 Marionette DEBUG Closed connection 379
06:53:21 INFO - 1501077201128 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49772
06:53:21 INFO - 1501077201129 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:21 INFO - 1501077201129 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201131 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201133 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d9a22f70-d685-4d3b-a659-e6fbc9d3efc1","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201135 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:53:21 INFO - 1501077201136 Marionette TRACE 380 <- [1,2,null,{}]
06:53:21 INFO - 1501077201138 Marionette DEBUG Closed connection 380
06:53:21 INFO - 1501077201138 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49773
06:53:21 INFO - 1501077201139 Marionette DEBUG Closed connection 381
06:53:21 INFO - 1501077201139 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49774
06:53:21 INFO - 1501077201140 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:21 INFO - 1501077201141 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201143 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201145 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d9f11913-a238-4eeb-a4f8-b653503bf913","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201147 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:53:21 INFO - 1501077201148 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"
06:53:21 INFO - 1501077201150 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"
06:53:21 INFO - 1501077201150 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"
06:53:21 INFO - 1501077201159 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"
06:53:21 INFO - 1501077201166 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"
06:53:21 INFO - 1501077201169 Marionette TRACE 382 <- [1,2,null,{}]
06:53:21 INFO - 1501077201170 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:53:21 INFO - 1501077201172 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"278812ec-d638-4e35-b285-294d9d338af5","ELEMENT":"278812ec-d638-4e35-b285-294d9d338af5"}}]
06:53:21 INFO - 1501077201173 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"278812ec-d638-4e35-b285-294d9d338af5"}]
06:53:21 INFO - 1501077201415 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:21 INFO - 1501077201416 Marionette TRACE 382 <- [1,4,null,{}]
06:53:21 INFO - 1501077201418 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
06:53:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501077201420 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:53:21 INFO - 1501077201421 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:53:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 319ms
06:53:21 INFO - TEST-START | test_click.py TestClick.test_input_file
06:53:21 INFO - 1501077201422 Marionette TRACE 382 <- [1,6,null,{}]
06:53:21 INFO - 1501077201424 Marionette DEBUG Closed connection 382
06:53:21 INFO - 1501077201425 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49775
06:53:21 INFO - 1501077201426 Marionette DEBUG Closed connection 383
06:53:21 INFO - 1501077201426 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49776
06:53:21 INFO - 1501077201427 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:21 INFO - 1501077201428 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201429 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201432 Marionette TRACE 384 <- [1,1,null,{"sessionId":"c096d40a-5d5c-479a-99e0-52b269f32ea6","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201434 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:53:21 INFO - 1501077201434 Marionette TRACE 384 <- [1,2,null,{}]
06:53:21 INFO - 1501077201435 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:21 INFO - 1501077201435 Marionette TRACE 384 <- [1,3,null,{}]
06:53:21 INFO - 1501077201436 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:21 INFO - 1501077201436 Marionette TRACE 384 <- [1,4,null,{}]
06:53:21 INFO - 1501077201439 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
06:53:21 INFO - TEST-START: test_click.py TestClick.test_input_file1501077201441 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:53:21 INFO - 1501077201442 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:53:21 INFO - 1501077201443 Marionette TRACE 384 <- [1,6,null,{}]
06:53:21 INFO - 1501077201444 Marionette DEBUG Closed connection 384
06:53:21 INFO - 1501077201445 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49777
06:53:21 INFO - 1501077201446 Marionette DEBUG Closed connection 385
06:53:21 INFO - 1501077201446 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49778
06:53:21 INFO - 1501077201447 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:21 INFO - 1501077201447 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201449 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201452 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1d9037ba-0b13-48c0-90b7-094b1c0f73a5","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201453 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:53:21 INFO - 1501077201454 Marionette TRACE 386 <- [1,2,null,{}]
06:53:21 INFO - 1501077201456 Marionette DEBUG Closed connection 386
06:53:21 INFO - 1501077201457 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49779
06:53:21 INFO - 1501077201457 Marionette DEBUG Closed connection 387
06:53:21 INFO - 1501077201458 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49780
06:53:21 INFO - 1501077201459 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:21 INFO - 1501077201459 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201461 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201463 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f50e4ea9-3b16-4705-9b26-075a5aef3499","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201465 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:53:21 INFO - 1501077201466 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"
06:53:21 INFO - 1501077201468 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"
06:53:21 INFO - 1501077201468 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"
06:53:21 INFO - 1501077201485 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:21 INFO - 1501077201486 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:21 INFO - 1501077201489 Marionette TRACE 388 <- [1,2,null,{}]
06:53:21 INFO - 1501077201490 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:53:21 INFO - 1501077201497 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1ea76afe-619f-4dd6-9184-7fa838b42921","ELEMENT":"1ea76afe-619f-4dd6-9184-7fa838b42921"}}]
06:53:21 INFO - 1501077201498 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"1ea76afe-619f-4dd6-9184-7fa838b42921"}]
06:53:21 INFO - 1501077201529 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:53:21 INFO - 1501077201531 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
06:53:21 INFO - TEST-END: test_click.py TestClick.test_input_file1501077201533 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:53:21 INFO - 1501077201534 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:53:21 INFO - 1501077201535 Marionette TRACE 388 <- [1,6,null,{}]
06:53:21 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 112ms
06:53:21 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:53:21 INFO - 1501077201537 Marionette DEBUG Closed connection 388
06:53:21 INFO - 1501077201538 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49781
06:53:21 INFO - 1501077201539 Marionette DEBUG Closed connection 389
06:53:21 INFO - 1501077201539 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49782
06:53:21 INFO - 1501077201540 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:21 INFO - 1501077201541 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201542 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201545 Marionette TRACE 390 <- [1,1,null,{"sessionId":"e001899b-caab-4a2b-8d42-4a240db73bc5","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201546 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:53:21 INFO - 1501077201547 Marionette TRACE 390 <- [1,2,null,{}]
06:53:21 INFO - 1501077201547 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:21 INFO - 1501077201548 Marionette TRACE 390 <- [1,3,null,{}]
06:53:21 INFO - 1501077201549 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:21 INFO - 1501077201549 Marionette TRACE 390 <- [1,4,null,{}]
06:53:21 INFO - 1501077201550 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
06:53:21 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501077201552 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:53:21 INFO - 1501077201553 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:53:21 INFO - 1501077201554 Marionette TRACE 390 <- [1,6,null,{}]
06:53:21 INFO - 1501077201556 Marionette DEBUG Closed connection 390
06:53:21 INFO - 1501077201557 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49783
06:53:21 INFO - 1501077201557 Marionette DEBUG Closed connection 391
06:53:21 INFO - 1501077201558 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49784
06:53:21 INFO - 1501077201559 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:21 INFO - 1501077201559 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201561 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201563 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a964ca75-e46d-4a6f-8bb1-53f17b8a0b30","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201565 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:53:21 INFO - 1501077201566 Marionette TRACE 392 <- [1,2,null,{}]
06:53:21 INFO - 1501077201568 Marionette DEBUG Closed connection 392
06:53:21 INFO - 1501077201568 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49785
06:53:21 INFO - 1501077201569 Marionette DEBUG Closed connection 393
06:53:21 INFO - 1501077201569 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49786
06:53:21 INFO - 1501077201570 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:21 INFO - 1501077201571 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201572 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201575 Marionette TRACE 394 <- [1,1,null,{"sessionId":"75ced229-76d2-4751-b864-47d17c01da9c","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201576 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
06:53:21 INFO - 1501077201578 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:21 INFO - 1501077201579 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:21 INFO - 1501077201580 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:53:21 INFO - 1501077201588 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"
06:53:21 INFO - 1501077201592 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"
06:53:21 INFO - 1501077201599 Marionette TRACE 394 <- [1,2,null,{}]
06:53:21 INFO - 1501077201600 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:53:21 INFO - 1501077201602 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a404404-bdaf-4722-b089-0fdddc913567","ELEMENT":"9a404404-bdaf-4722-b089-0fdddc913567"}}]
06:53:21 INFO - 1501077201603 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:53:21 INFO - 1501077201605 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"89e19eb5-eee9-40c3-8441-dd367adfb290","ELEMENT":"89e19eb5-eee9-40c3-8441-dd367adfb290"}}]
06:53:21 INFO - 1501077201606 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9a404404-bdaf-4722-b089-0fdddc913567"}]
06:53:21 INFO - 1501077201839 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:53:21 INFO - 1501077201840 Marionette TRACE 394 <- [1,5,null,{}]
06:53:21 INFO - 1501077201841 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"89e19eb5-eee9-40c3-8441-dd367adfb290"}]
06:53:21 INFO - 1501077201854 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:53:21 INFO - 1501077201856 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:53:21 INFO - 1501077201858 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:53:21 INFO - 1501077201859 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
06:53:21 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501077201861 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:53:21 INFO - 1501077201862 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:53:21 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 328ms
06:53:21 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:53:21 INFO - 1501077201863 Marionette TRACE 394 <- [1,9,null,{}]
06:53:21 INFO - 1501077201865 Marionette DEBUG Closed connection 394
06:53:21 INFO - 1501077201866 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49787
06:53:21 INFO - 1501077201866 Marionette DEBUG Closed connection 395
06:53:21 INFO - 1501077201867 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49788
06:53:21 INFO - 1501077201868 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:21 INFO - 1501077201868 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201876 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201879 Marionette TRACE 396 <- [1,1,null,{"sessionId":"88339f83-b494-478f-a34b-46fee6d88490","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201881 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:53:21 INFO - 1501077201881 Marionette TRACE 396 <- [1,2,null,{}]
06:53:21 INFO - 1501077201882 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:53:21 INFO - 1501077201882 Marionette TRACE 396 <- [1,3,null,{}]
06:53:21 INFO - 1501077201883 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:53:21 INFO - 1501077201884 Marionette TRACE 396 <- [1,4,null,{}]
06:53:21 INFO - 1501077201885 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
06:53:21 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501077201887 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:53:21 INFO - 1501077201888 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:53:21 INFO - 1501077201889 Marionette TRACE 396 <- [1,6,null,{}]
06:53:21 INFO - 1501077201891 Marionette DEBUG Closed connection 396
06:53:21 INFO - 1501077201891 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49789
06:53:21 INFO - 1501077201892 Marionette DEBUG Closed connection 397
06:53:21 INFO - 1501077201892 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49790
06:53:21 INFO - 1501077201893 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:53:21 INFO - 1501077201894 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201896 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201898 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b651b5af-89ac-46b0-a1d7-2aab56911a62","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201900 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:53:21 INFO - 1501077201901 Marionette TRACE 398 <- [1,2,null,{}]
06:53:21 INFO - 1501077201903 Marionette DEBUG Closed connection 398
06:53:21 INFO - 1501077201903 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49791
06:53:21 INFO - 1501077201904 Marionette DEBUG Closed connection 399
06:53:21 INFO - 1501077201905 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49792
06:53:21 INFO - 1501077201906 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:53:21 INFO - 1501077201906 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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}
06:53:21 INFO - 1501077201908 Marionette DEBUG Register listener.js for window 12
06:53:21 INFO - 1501077201911 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1788535c-af64-4cc7-b576-efb3d65c602c","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":3968,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-331.001\\AppData\\Local\\Temp\\tmpwof5en.mozrunner","moz:accessibilityChecks":false}}]
06:53:21 INFO - 1501077201913 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
06:53:21 INFO - 1501077201914 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"
06:53:21 INFO - 1501077201916 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"
06:53:21 INFO - 1501077201916 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"
06:53:21 INFO - 1501077201934 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"
06:53:21 INFO - 1501077201935 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"
06:53:21 INFO - 1501077201938 Marionette TRACE 400 <- [1,2,null,{}]
06:53:21 INFO - 1501077201939 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:53:21 INFO - 1501077201940 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a5a330d3-4bab-4d41-b835-f578528f546a","ELEMENT":"a5a330d3-4bab-4d41-b835-f578528f546a"}}]
06:53:21 INFO - 1501077201942 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a5a330d3-4bab-4d41-b835-f578528f546a"}]
06:53:21 INFO - 1501077201968 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:53:21 INFO - 1501077201970 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a5a330d3-4bab-4d41-b835-f578528f546a"}]
06:53:21 INFO - 1501077201984 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element