could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:38:21 INFO - 1500482301863 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
09:38:21 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500482301866 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:38:21 INFO - 1500482301866 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:38:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 120ms
09:38:21 INFO - 1500482301868 Marionette TRACE 352 <- [1,6,null,{}]
09:38:21 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:38:21 INFO - 1500482301869 Marionette DEBUG Closed connection 352
09:38:21 INFO - 1500482301870 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49908
09:38:21 INFO - 1500482301871 Marionette DEBUG Closed connection 353
09:38:21 INFO - 1500482301872 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49909
09:38:21 INFO - 1500482301873 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:21 INFO - 1500482301875 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:21 INFO - 1500482301877 Marionette DEBUG Register listener.js for window 6442450945
09:38:21 INFO - 1500482301880 Marionette TRACE 354 <- [1,1,null,{"sessionId":"c6ecf88a-0592-40e0-91d8-9712764f0517","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:21 INFO - 1500482301881 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:38:21 INFO - 1500482301881 Marionette TRACE 354 <- [1,2,null,{}]
09:38:21 INFO - 1500482301882 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:38:21 INFO - 1500482301883 Marionette TRACE 354 <- [1,3,null,{}]
09:38:21 INFO - 1500482301884 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:38:21 INFO - 1500482301884 Marionette TRACE 354 <- [1,4,null,{}]
09:38:21 INFO - 1500482301886 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
09:38:21 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500482301888 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:38:21 INFO - 1500482301889 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:38:21 INFO - 1500482301890 Marionette TRACE 354 <- [1,6,null,{}]
09:38:21 INFO - 1500482301892 Marionette DEBUG Closed connection 354
09:38:21 INFO - 1500482301892 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49910
09:38:21 INFO - 1500482301895 Marionette DEBUG Closed connection 355
09:38:21 INFO - 1500482301896 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49911
09:38:21 INFO - 1500482301897 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:21 INFO - 1500482301897 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:21 INFO - 1500482301899 Marionette DEBUG Register listener.js for window 6442450945
09:38:21 INFO - 1500482301902 Marionette TRACE 356 <- [1,1,null,{"sessionId":"1cd9ecf7-f893-45b0-80f4-7b25ad065217","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:21 INFO - 1500482301903 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:38:21 INFO - 1500482301904 Marionette TRACE 356 <- [1,2,null,{}]
09:38:21 INFO - 1500482301905 Marionette DEBUG Closed connection 356
09:38:21 INFO - 1500482301906 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49912
09:38:21 INFO - 1500482301907 Marionette DEBUG Closed connection 357
09:38:21 INFO - 1500482301907 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49913
09:38:21 INFO - 1500482301908 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:38:21 INFO - 1500482301909 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:21 INFO - 1500482301911 Marionette DEBUG Register listener.js for window 6442450945
09:38:21 INFO - 1500482301914 Marionette TRACE 358 <- [1,1,null,{"sessionId":"1b96656b-a32b-42ee-97d8-251d24d7edfc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:21 INFO - 1500482301924 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49465/clicks.html"}]
09:38:21 INFO - 1500482301926 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49465/hidden.html"
09:38:21 INFO - 1500482301933 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49465/hidden.html"
09:38:21 INFO - 1500482301933 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49465/hidden.html"
09:38:21 INFO - 1500482301943 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49465/clicks.html"
09:38:21 INFO - 1500482301944 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49465/clicks.html"
09:38:21 INFO - 1500482301947 Marionette TRACE 358 <- [1,2,null,{}]
09:38:21 INFO - 1500482301952 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:38:21 INFO - 1500482301955 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9350eeda-16ca-4d53-b037-e4ddde766ed3","ELEMENT":"9350eeda-16ca-4d53-b037-e4ddde766ed3"}}]
09:38:21 INFO - 1500482301956 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"9350eeda-16ca-4d53-b037-e4ddde766ed3"}]
09:38:22 INFO - 1500482301998 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49465/clicks.html"
09:38:22 INFO - 1500482302005 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49465/clicks.html"
09:38:22 INFO - 1500482302005 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49465/clicks.html"
09:38:22 INFO - 1500482302011 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49465/test.html"
09:38:22 INFO - 1500482302014 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49465/test.html"
09:38:22 INFO - 1500482302020 Marionette TRACE 358 <- [1,4,null,{}]
09:38:22 INFO - 1500482302029 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:38:22 INFO - 1500482302032 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"beacd9ab-0531-4e25-9dfd-312edfaafd3d","ELEMENT":"beacd9ab-0531-4e25-9dfd-312edfaafd3d"}}]
09:38:22 INFO - 1500482302033 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:38:22 INFO - 1500482302034 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:38:22 INFO - 1500482302035 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
09:38:22 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500482302038 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:38:22 INFO - 1500482302039 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:38:22 INFO - 1500482302040 Marionette TRACE 358 <- [1,8,null,{}]
09:38:22 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 172ms
09:38:22 INFO - TEST-START | test_click.py TestClick.test_container_element
09:38:22 INFO - 1500482302042 Marionette DEBUG Closed connection 358
09:38:22 INFO - 1500482302043 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49916
09:38:22 INFO - 1500482302044 Marionette DEBUG Closed connection 359
09:38:22 INFO - 1500482302044 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49917
09:38:22 INFO - 1500482302045 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:22 INFO - 1500482302046 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302048 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302051 Marionette TRACE 360 <- [1,1,null,{"sessionId":"f473eced-07a8-4d3b-8fc5-d36cb92ecba0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302052 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:38:22 INFO - 1500482302053 Marionette TRACE 360 <- [1,2,null,{}]
09:38:22 INFO - 1500482302055 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:38:22 INFO - 1500482302055 Marionette TRACE 360 <- [1,3,null,{}]
09:38:22 INFO - 1500482302056 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:38:22 INFO - 1500482302056 Marionette TRACE 360 <- [1,4,null,{}]
09:38:22 INFO - 1500482302058 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
09:38:22 INFO - TEST-START: test_click.py TestClick.test_container_element1500482302061 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:38:22 INFO - 1500482302063 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:38:22 INFO - 1500482302064 Marionette TRACE 360 <- [1,6,null,{}]
09:38:22 INFO - 1500482302066 Marionette DEBUG Closed connection 360
09:38:22 INFO - 1500482302066 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49918
09:38:22 INFO - 1500482302067 Marionette DEBUG Closed connection 361
09:38:22 INFO - 1500482302068 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49919
09:38:22 INFO - 1500482302069 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:22 INFO - 1500482302070 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302072 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302075 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3d96c465-396f-440a-932a-b8f1ea268731","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302076 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:38:22 INFO - 1500482302077 Marionette TRACE 362 <- [1,2,null,{}]
09:38:22 INFO - 1500482302078 Marionette DEBUG Closed connection 362
09:38:22 INFO - 1500482302079 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49920
09:38:22 INFO - 1500482302079 Marionette DEBUG Closed connection 363
09:38:22 INFO - 1500482302080 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49921
09:38:22 INFO - 1500482302081 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:38:22 INFO - 1500482302082 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302089 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302092 Marionette TRACE 364 <- [1,1,null,{"sessionId":"4724eb43-5fc6-4af3-a913-e368c63be5b2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302093 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:38:22 INFO - 1500482302095 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49465/test.html"
09:38:22 INFO - 1500482302098 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49465/test.html"
09:38:22 INFO - 1500482302098 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49465/test.html"
09:38:22 INFO - 1500482302104 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302107 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302116 Marionette TRACE 364 <- [1,2,null,{}]
09:38:22 INFO - 1500482302120 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:38:22 INFO - 1500482302122 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1cedf687-5d23-41a5-bd71-6d4d3a26ebc2","ELEMENT":"1cedf687-5d23-41a5-bd71-6d4d3a26ebc2"}}]
09:38:22 INFO - 1500482302128 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"1cedf687-5d23-41a5-bd71-6d4d3a26ebc2"}]
09:38:22 INFO - 1500482302366 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:38:22 INFO - 1500482302367 Marionette TRACE 364 <- [1,4,null,{}]
09:38:22 INFO - 1500482302368 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"1cedf687-5d23-41a5-bd71-6d4d3a26ebc2","name":"selected"}]
09:38:22 INFO - 1500482302372 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:38:22 INFO - 1500482302374 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
09:38:22 INFO - TEST-END: test_click.py TestClick.test_container_element1500482302376 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:38:22 INFO - 1500482302377 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:38:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 338ms
09:38:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:38:22 INFO - 1500482302379 Marionette TRACE 364 <- [1,7,null,{}]
09:38:22 INFO - 1500482302380 Marionette DEBUG Closed connection 364
09:38:22 INFO - 1500482302381 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49922
09:38:22 INFO - 1500482302382 Marionette DEBUG Closed connection 365
09:38:22 INFO - 1500482302382 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49923
09:38:22 INFO - 1500482302383 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:22 INFO - 1500482302384 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302386 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302389 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1db4d0e9-02e4-4498-8ed8-4972d36b1e44","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302391 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:38:22 INFO - 1500482302391 Marionette TRACE 366 <- [1,2,null,{}]
09:38:22 INFO - 1500482302392 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:38:22 INFO - 1500482302393 Marionette TRACE 366 <- [1,3,null,{}]
09:38:22 INFO - 1500482302393 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:38:22 INFO - 1500482302394 Marionette TRACE 366 <- [1,4,null,{}]
09:38:22 INFO - 1500482302396 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
09:38:22 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500482302398 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:38:22 INFO - 1500482302399 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:38:22 INFO - 1500482302400 Marionette TRACE 366 <- [1,6,null,{}]
09:38:22 INFO - 1500482302402 Marionette DEBUG Closed connection 366
09:38:22 INFO - 1500482302402 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49924
09:38:22 INFO - 1500482302403 Marionette DEBUG Closed connection 367
09:38:22 INFO - 1500482302404 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49925
09:38:22 INFO - 1500482302405 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:22 INFO - 1500482302405 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302407 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302410 Marionette TRACE 368 <- [1,1,null,{"sessionId":"9c3261fa-05f5-4ac0-b804-b65084dff9f8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302411 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:38:22 INFO - 1500482302412 Marionette TRACE 368 <- [1,2,null,{}]
09:38:22 INFO - 1500482302413 Marionette DEBUG Closed connection 368
09:38:22 INFO - 1500482302414 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49926
09:38:22 INFO - 1500482302414 Marionette DEBUG Closed connection 369
09:38:22 INFO - 1500482302415 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49927
09:38:22 INFO - 1500482302416 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:38:22 INFO - 1500482302417 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302418 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302421 Marionette TRACE 370 <- [1,1,null,{"sessionId":"fe8b57fb-97cb-47c5-9765-1bd2deb6bd93","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302422 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:38:22 INFO - 1500482302424 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302425 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302426 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302431 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302434 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302455 Marionette TRACE 370 <- [1,2,null,{}]
09:38:22 INFO - 1500482302465 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:38:22 INFO - 1500482302469 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5ebf3ad6-98c7-44f3-9730-b3d1ca93ff3a","ELEMENT":"5ebf3ad6-98c7-44f3-9730-b3d1ca93ff3a"}}]
09:38:22 INFO - 1500482302470 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"5ebf3ad6-98c7-44f3-9730-b3d1ca93ff3a"}]
09:38:22 INFO - 1500482302711 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:38:22 INFO - 1500482302712 Marionette TRACE 370 <- [1,4,null,{}]
09:38:22 INFO - 1500482302713 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"5ebf3ad6-98c7-44f3-9730-b3d1ca93ff3a","name":"selected"}]
09:38:22 INFO - 1500482302717 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:38:22 INFO - 1500482302718 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
09:38:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500482302723 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:38:22 INFO - 1500482302723 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:38:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 347ms
09:38:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:38:22 INFO - 1500482302725 Marionette TRACE 370 <- [1,7,null,{}]
09:38:22 INFO - 1500482302727 Marionette DEBUG Closed connection 370
09:38:22 INFO - 1500482302728 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49928
09:38:22 INFO - 1500482302728 Marionette DEBUG Closed connection 371
09:38:22 INFO - 1500482302729 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49929
09:38:22 INFO - 1500482302730 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:22 INFO - 1500482302731 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302733 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302736 Marionette TRACE 372 <- [1,1,null,{"sessionId":"eb39c35e-c39e-41a7-9dcf-d715f4d95d4d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302737 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:38:22 INFO - 1500482302737 Marionette TRACE 372 <- [1,2,null,{}]
09:38:22 INFO - 1500482302738 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:38:22 INFO - 1500482302739 Marionette TRACE 372 <- [1,3,null,{}]
09:38:22 INFO - 1500482302740 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:38:22 INFO - 1500482302740 Marionette TRACE 372 <- [1,4,null,{}]
09:38:22 INFO - 1500482302742 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
09:38:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500482302744 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:38:22 INFO - 1500482302745 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:38:22 INFO - 1500482302746 Marionette TRACE 372 <- [1,6,null,{}]
09:38:22 INFO - 1500482302747 Marionette DEBUG Closed connection 372
09:38:22 INFO - 1500482302748 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49930
09:38:22 INFO - 1500482302748 Marionette DEBUG Closed connection 373
09:38:22 INFO - 1500482302749 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49931
09:38:22 INFO - 1500482302750 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:22 INFO - 1500482302751 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302753 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302755 Marionette TRACE 374 <- [1,1,null,{"sessionId":"28c0955c-334f-4951-b57b-c8f182d150d1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302756 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:38:22 INFO - 1500482302757 Marionette TRACE 374 <- [1,2,null,{}]
09:38:22 INFO - 1500482302759 Marionette DEBUG Closed connection 374
09:38:22 INFO - 1500482302759 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49932
09:38:22 INFO - 1500482302761 Marionette DEBUG Closed connection 375
09:38:22 INFO - 1500482302761 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49933
09:38:22 INFO - 1500482302762 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:38:22 INFO - 1500482302763 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:22 INFO - 1500482302765 Marionette DEBUG Register listener.js for window 6442450945
09:38:22 INFO - 1500482302768 Marionette TRACE 376 <- [1,1,null,{"sessionId":"580a6bc0-cd66-42d0-af8c-4f648d9beff5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:22 INFO - 1500482302769 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
09:38:22 INFO - 1500482302770 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302772 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302772 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:22 INFO - 1500482302778 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:38:22 INFO - 1500482302780 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:38:22 INFO - 1500482302796 Marionette TRACE 376 <- [1,2,null,{}]
09:38:22 INFO - 1500482302804 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:38:22 INFO - 1500482302810 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"855b0bcc-be20-4aca-86fe-5a0ece4f7587","ELEMENT":"855b0bcc-be20-4aca-86fe-5a0ece4f7587"}}]
09:38:22 INFO - 1500482302812 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"855b0bcc-be20-4aca-86fe-5a0ece4f7587"}]
09:38:23 INFO - 1500482303048 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:38:23 INFO - 1500482303049 Marionette TRACE 376 <- [1,4,null,{}]
09:38:23 INFO - 1500482303050 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
09:38:23 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500482303053 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:38:23 INFO - 1500482303054 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:38:23 INFO - 1500482303055 Marionette TRACE 376 <- [1,6,null,{}]
09:38:23 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 330ms
09:38:23 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:38:23 INFO - 1500482303057 Marionette DEBUG Closed connection 376
09:38:23 INFO - 1500482303058 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49934
09:38:23 INFO - 1500482303058 Marionette DEBUG Closed connection 377
09:38:23 INFO - 1500482303059 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49935
09:38:23 INFO - 1500482303060 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:23 INFO - 1500482303061 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303063 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303065 Marionette TRACE 378 <- [1,1,null,{"sessionId":"bd97f031-9963-4948-940b-59c583e3af8c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303067 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:38:23 INFO - 1500482303067 Marionette TRACE 378 <- [1,2,null,{}]
09:38:23 INFO - 1500482303068 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:38:23 INFO - 1500482303068 Marionette TRACE 378 <- [1,3,null,{}]
09:38:23 INFO - 1500482303069 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:38:23 INFO - 1500482303070 Marionette TRACE 378 <- [1,4,null,{}]
09:38:23 INFO - 1500482303071 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
09:38:23 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500482303074 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:38:23 INFO - 1500482303075 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:38:23 INFO - 1500482303076 Marionette TRACE 378 <- [1,6,null,{}]
09:38:23 INFO - 1500482303077 Marionette DEBUG Closed connection 378
09:38:23 INFO - 1500482303078 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49936
09:38:23 INFO - 1500482303078 Marionette DEBUG Closed connection 379
09:38:23 INFO - 1500482303079 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49937
09:38:23 INFO - 1500482303080 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:23 INFO - 1500482303081 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303083 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303085 Marionette TRACE 380 <- [1,1,null,{"sessionId":"456ed069-8e06-4243-9ba3-490a8b36d688","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303086 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:38:23 INFO - 1500482303087 Marionette TRACE 380 <- [1,2,null,{}]
09:38:23 INFO - 1500482303089 Marionette DEBUG Closed connection 380
09:38:23 INFO - 1500482303089 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49938
09:38:23 INFO - 1500482303090 Marionette DEBUG Closed connection 381
09:38:23 INFO - 1500482303091 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49939
09:38:23 INFO - 1500482303092 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:38:23 INFO - 1500482303093 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303095 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303097 Marionette TRACE 382 <- [1,1,null,{"sessionId":"37f18db2-c7c4-4484-b5e2-ddef8312757e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303098 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
09:38:23 INFO - 1500482303100 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:38:23 INFO - 1500482303102 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:38:23 INFO - 1500482303102 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
09:38:23 INFO - 1500482303110 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:23 INFO - 1500482303111 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:23 INFO - 1500482303118 Marionette TRACE 382 <- [1,2,null,{}]
09:38:23 INFO - 1500482303121 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:38:23 INFO - 1500482303127 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b58c7043-f30e-46ce-adb6-8046667bf8af","ELEMENT":"b58c7043-f30e-46ce-adb6-8046667bf8af"}}]
09:38:23 INFO - 1500482303128 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"b58c7043-f30e-46ce-adb6-8046667bf8af"}]
09:38:23 INFO - 1500482303373 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:38:23 INFO - 1500482303374 Marionette TRACE 382 <- [1,4,null,{}]
09:38:23 INFO - 1500482303375 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
09:38:23 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500482303378 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:38:23 INFO - 1500482303379 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:38:23 INFO - 1500482303380 Marionette TRACE 382 <- [1,6,null,{}]
09:38:23 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 325ms
09:38:23 INFO - TEST-START | test_click.py TestClick.test_input_file
09:38:23 INFO - 1500482303382 Marionette DEBUG Closed connection 382
09:38:23 INFO - 1500482303383 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49940
09:38:23 INFO - 1500482303383 Marionette DEBUG Closed connection 383
09:38:23 INFO - 1500482303384 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49941
09:38:23 INFO - 1500482303385 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:23 INFO - 1500482303386 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303388 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303391 Marionette TRACE 384 <- [1,1,null,{"sessionId":"230e288d-dcdf-418a-8a21-ff3b347a05fe","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303392 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:38:23 INFO - 1500482303392 Marionette TRACE 384 <- [1,2,null,{}]
09:38:23 INFO - 1500482303393 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:38:23 INFO - 1500482303394 Marionette TRACE 384 <- [1,3,null,{}]
09:38:23 INFO - 1500482303394 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:38:23 INFO - 1500482303395 Marionette TRACE 384 <- [1,4,null,{}]
09:38:23 INFO - 1500482303396 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
09:38:23 INFO - TEST-START: test_click.py TestClick.test_input_file1500482303399 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:38:23 INFO - 1500482303400 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:38:23 INFO - 1500482303401 Marionette TRACE 384 <- [1,6,null,{}]
09:38:23 INFO - 1500482303402 Marionette DEBUG Closed connection 384
09:38:23 INFO - 1500482303403 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49942
09:38:23 INFO - 1500482303403 Marionette DEBUG Closed connection 385
09:38:23 INFO - 1500482303404 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49943
09:38:23 INFO - 1500482303405 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:23 INFO - 1500482303405 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303407 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303410 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5427fb83-c9b4-4028-b2f5-aa2a1c238168","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303411 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:38:23 INFO - 1500482303412 Marionette TRACE 386 <- [1,2,null,{}]
09:38:23 INFO - 1500482303414 Marionette DEBUG Closed connection 386
09:38:23 INFO - 1500482303414 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49944
09:38:23 INFO - 1500482303415 Marionette DEBUG Closed connection 387
09:38:23 INFO - 1500482303416 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49945
09:38:23 INFO - 1500482303417 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:38:23 INFO - 1500482303417 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303419 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303422 Marionette TRACE 388 <- [1,1,null,{"sessionId":"16d1b9a8-f8af-4eeb-a71d-54cbceb9ffac","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303423 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:38:23 INFO - 1500482303425 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:23 INFO - 1500482303427 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:23 INFO - 1500482303427 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
09:38:23 INFO - 1500482303433 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:38:23 INFO - 1500482303436 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:38:23 INFO - 1500482303437 Marionette TRACE 388 <- [1,2,null,{}]
09:38:23 INFO - 1500482303446 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:38:23 INFO - 1500482303450 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2770f7a7-7e56-4795-92e4-190f28ba878e","ELEMENT":"2770f7a7-7e56-4795-92e4-190f28ba878e"}}]
09:38:23 INFO - 1500482303451 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2770f7a7-7e56-4795-92e4-190f28ba878e"}]
09:38:23 INFO - 1500482303477 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:38:23 INFO - 1500482303479 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
09:38:23 INFO - TEST-END: test_click.py TestClick.test_input_file1500482303482 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:38:23 INFO - 1500482303482 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:38:23 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 103ms
09:38:23 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:38:23 INFO - 1500482303484 Marionette TRACE 388 <- [1,6,null,{}]
09:38:23 INFO - 1500482303485 Marionette DEBUG Closed connection 388
09:38:23 INFO - 1500482303486 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49946
09:38:23 INFO - 1500482303487 Marionette DEBUG Closed connection 389
09:38:23 INFO - 1500482303487 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49947
09:38:23 INFO - 1500482303488 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:23 INFO - 1500482303489 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303491 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303494 Marionette TRACE 390 <- [1,1,null,{"sessionId":"dcb11e9e-e3e3-417f-8e6a-32109632cef0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303495 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:38:23 INFO - 1500482303495 Marionette TRACE 390 <- [1,2,null,{}]
09:38:23 INFO - 1500482303496 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:38:23 INFO - 1500482303497 Marionette TRACE 390 <- [1,3,null,{}]
09:38:23 INFO - 1500482303497 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:38:23 INFO - 1500482303498 Marionette TRACE 390 <- [1,4,null,{}]
09:38:23 INFO - 1500482303499 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
09:38:23 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500482303502 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:38:23 INFO - 1500482303502 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:38:23 INFO - 1500482303504 Marionette TRACE 390 <- [1,6,null,{}]
09:38:23 INFO - 1500482303505 Marionette DEBUG Closed connection 390
09:38:23 INFO - 1500482303505 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49948
09:38:23 INFO - 1500482303506 Marionette DEBUG Closed connection 391
09:38:23 INFO - 1500482303507 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49949
09:38:23 INFO - 1500482303508 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:23 INFO - 1500482303508 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303510 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303513 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ebfd5faa-a191-4d6a-8392-05c08400e143","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303514 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:38:23 INFO - 1500482303515 Marionette TRACE 392 <- [1,2,null,{}]
09:38:23 INFO - 1500482303516 Marionette DEBUG Closed connection 392
09:38:23 INFO - 1500482303517 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49950
09:38:23 INFO - 1500482303518 Marionette DEBUG Closed connection 393
09:38:23 INFO - 1500482303518 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49951
09:38:23 INFO - 1500482303519 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:38:23 INFO - 1500482303520 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303522 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303524 Marionette TRACE 394 <- [1,1,null,{"sessionId":"9f49181a-f52a-4651-8e53-5b76019d7f33","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303526 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
09:38:23 INFO - 1500482303527 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:38:23 INFO - 1500482303530 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:38:23 INFO - 1500482303530 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:38:23 INFO - 1500482303537 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:38:23 INFO - 1500482303541 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:38:23 INFO - 1500482303547 Marionette TRACE 394 <- [1,2,null,{}]
09:38:23 INFO - 1500482303551 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:38:23 INFO - 1500482303554 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5e9a880a-15c8-45f7-a2d6-2bbe62b1f023","ELEMENT":"5e9a880a-15c8-45f7-a2d6-2bbe62b1f023"}}]
09:38:23 INFO - 1500482303556 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:38:23 INFO - 1500482303561 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f38752a6-98c7-4fcf-9acc-37bd2a5a78a1","ELEMENT":"f38752a6-98c7-4fcf-9acc-37bd2a5a78a1"}}]
09:38:23 INFO - 1500482303564 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"5e9a880a-15c8-45f7-a2d6-2bbe62b1f023"}]
09:38:23 INFO - 1500482303799 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:38:23 INFO - 1500482303800 Marionette TRACE 394 <- [1,5,null,{}]
09:38:23 INFO - 1500482303801 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"f38752a6-98c7-4fcf-9acc-37bd2a5a78a1"}]
09:38:23 INFO - 1500482303815 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:38:23 INFO - 1500482303817 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
09:38:23 INFO - 1500482303820 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:38:23 INFO - 1500482303821 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
09:38:23 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500482303824 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:38:23 INFO - 1500482303825 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:38:23 INFO - 1500482303826 Marionette TRACE 394 <- [1,9,null,{}]
09:38:23 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 341ms
09:38:23 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:38:23 INFO - 1500482303827 Marionette DEBUG Closed connection 394
09:38:23 INFO - 1500482303828 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49952
09:38:23 INFO - 1500482303829 Marionette DEBUG Closed connection 395
09:38:23 INFO - 1500482303830 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49953
09:38:23 INFO - 1500482303831 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:23 INFO - 1500482303831 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303833 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303844 Marionette TRACE 396 <- [1,1,null,{"sessionId":"c3707697-0b84-4051-b8e6-71f30d6ce28d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303845 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:38:23 INFO - 1500482303846 Marionette TRACE 396 <- [1,2,null,{}]
09:38:23 INFO - 1500482303846 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:38:23 INFO - 1500482303847 Marionette TRACE 396 <- [1,3,null,{}]
09:38:23 INFO - 1500482303848 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:38:23 INFO - 1500482303848 Marionette TRACE 396 <- [1,4,null,{}]
09:38:23 INFO - 1500482303850 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
09:38:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500482303852 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:38:23 INFO - 1500482303853 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:38:23 INFO - 1500482303855 Marionette TRACE 396 <- [1,6,null,{}]
09:38:23 INFO - 1500482303856 Marionette DEBUG Closed connection 396
09:38:23 INFO - 1500482303856 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49954
09:38:23 INFO - 1500482303857 Marionette DEBUG Closed connection 397
09:38:23 INFO - 1500482303858 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49955
09:38:23 INFO - 1500482303859 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:38:23 INFO - 1500482303859 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303861 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303864 Marionette TRACE 398 <- [1,1,null,{"sessionId":"62ebf455-8266-457e-adbc-ff042444ce05","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303865 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:38:23 INFO - 1500482303866 Marionette TRACE 398 <- [1,2,null,{}]
09:38:23 INFO - 1500482303868 Marionette DEBUG Closed connection 398
09:38:23 INFO - 1500482303868 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49956
09:38:23 INFO - 1500482303869 Marionette DEBUG Closed connection 399
09:38:23 INFO - 1500482303870 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49957
09:38:23 INFO - 1500482303871 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:38:23 INFO - 1500482303871 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}
09:38:23 INFO - 1500482303873 Marionette DEBUG Register listener.js for window 6442450945
09:38:23 INFO - 1500482303876 Marionette TRACE 400 <- [1,1,null,{"sessionId":"aecb8249-8978-4226-95b2-bf66fd6c21e1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3376,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpc9gxcn.mozrunner","moz:accessibilityChecks":false}}]
09:38:23 INFO - 1500482303877 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
09:38:23 INFO - 1500482303879 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:38:23 INFO - 1500482303880 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:38:23 INFO - 1500482303880 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
09:38:23 INFO - 1500482303886 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
09:38:23 INFO - 1500482303889 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
09:38:23 INFO - 1500482303896 Marionette TRACE 400 <- [1,2,null,{}]
09:38:23 INFO - 1500482303900 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:38:23 INFO - 1500482303902 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"754a1b52-293c-4c11-b4c6-e136bfcc1938","ELEMENT":"754a1b52-293c-4c11-b4c6-e136bfcc1938"}}]
09:38:23 INFO - 1500482303903 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"754a1b52-293c-4c11-b4c6-e136bfcc1938"}]
09:38:23 INFO - 1500482303929 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:38:23 INFO - 1500482303930 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"754a1b52-293c-4c11-b4c6-e136bfcc1938"}]
09:38:23 INFO - 1500482303944 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element