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:43:33 INFO - 1500990213865 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:43:33 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500990213867 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:43:33 INFO - 1500990213868 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:43:33 INFO - 1500990213869 Marionette TRACE 352 <- [1,6,null,{}]
06:43:33 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 131ms
06:43:33 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:43:33 INFO - 1500990213872 Marionette DEBUG Closed connection 352
06:43:33 INFO - 1500990213873 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49897
06:43:33 INFO - 1500990213873 Marionette DEBUG Closed connection 353
06:43:33 INFO - 1500990213874 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49898
06:43:33 INFO - 1500990213875 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:33 INFO - 1500990213876 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:33 INFO - 1500990213877 Marionette DEBUG Register listener.js for window 12
06:43:33 INFO - 1500990213880 Marionette TRACE 354 <- [1,1,null,{"sessionId":"2e9f616f-b261-4092-acf7-3d44528e898c","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:33 INFO - 1500990213882 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:43:33 INFO - 1500990213882 Marionette TRACE 354 <- [1,2,null,{}]
06:43:33 INFO - 1500990213883 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:43:33 INFO - 1500990213883 Marionette TRACE 354 <- [1,3,null,{}]
06:43:33 INFO - 1500990213884 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:43:33 INFO - 1500990213885 Marionette TRACE 354 <- [1,4,null,{}]
06:43:33 INFO - 1500990213886 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:43:33 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500990213889 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:43:33 INFO - 1500990213889 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:43:33 INFO - 1500990213891 Marionette TRACE 354 <- [1,6,null,{}]
06:43:33 INFO - 1500990213893 Marionette DEBUG Closed connection 354
06:43:33 INFO - 1500990213893 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49899
06:43:33 INFO - 1500990213894 Marionette DEBUG Closed connection 355
06:43:33 INFO - 1500990213895 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49900
06:43:33 INFO - 1500990213896 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:33 INFO - 1500990213896 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:33 INFO - 1500990213898 Marionette DEBUG Register listener.js for window 12
06:43:33 INFO - 1500990213901 Marionette TRACE 356 <- [1,1,null,{"sessionId":"8a77c0ed-6ae1-4b76-a6b2-4c91d4f7a8bd","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:33 INFO - 1500990213902 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:43:33 INFO - 1500990213903 Marionette TRACE 356 <- [1,2,null,{}]
06:43:33 INFO - 1500990213905 Marionette DEBUG Closed connection 356
06:43:33 INFO - 1500990213906 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49901
06:43:33 INFO - 1500990213906 Marionette DEBUG Closed connection 357
06:43:33 INFO - 1500990213907 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49902
06:43:33 INFO - 1500990213908 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:43:33 INFO - 1500990213909 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:33 INFO - 1500990213910 Marionette DEBUG Register listener.js for window 12
06:43:33 INFO - 1500990213915 Marionette TRACE 358 <- [1,1,null,{"sessionId":"2748c394-51d7-494f-b8cf-924d74d08849","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:33 INFO - 1500990213917 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49466/clicks.html"}]
06:43:33 INFO - 1500990213919 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49466/hidden.html"
06:43:33 INFO - 1500990213924 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49466/hidden.html"
06:43:33 INFO - 1500990213924 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49466/hidden.html"
06:43:33 INFO - 1500990213942 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49466/clicks.html"
06:43:33 INFO - 1500990213943 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49466/clicks.html"
06:43:33 INFO - 1500990213947 Marionette TRACE 358 <- [1,2,null,{}]
06:43:33 INFO - 1500990213948 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:43:33 INFO - 1500990213950 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"70ff7c25-3049-4a97-b073-b330f3c5effe","ELEMENT":"70ff7c25-3049-4a97-b073-b330f3c5effe"}}]
06:43:33 INFO - 1500990213958 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"70ff7c25-3049-4a97-b073-b330f3c5effe"}]
06:43:34 INFO - 1500990213998 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49466/clicks.html"
06:43:34 INFO - 1500990214005 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49466/clicks.html"
06:43:34 INFO - 1500990214006 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49466/clicks.html"
06:43:34 INFO - 1500990214024 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49466/test.html"
06:43:34 INFO - 1500990214026 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49466/test.html"
06:43:34 INFO - 1500990214028 Marionette TRACE 358 <- [1,4,null,{}]
06:43:34 INFO - 1500990214029 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:43:34 INFO - 1500990214033 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1abfb5fd-3226-4a4f-9a9f-5a1c2d8ea292","ELEMENT":"1abfb5fd-3226-4a4f-9a9f-5a1c2d8ea292"}}]
06:43:34 INFO - 1500990214034 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:43:34 INFO - 1500990214034 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:43:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 178ms
06:43:34 INFO - TEST-START | test_click.py TestClick.test_container_element
06:43:34 INFO - 1500990214043 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:43:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500990214046 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:43:34 INFO - 1500990214047 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:43:34 INFO - 1500990214048 Marionette TRACE 358 <- [1,8,null,{}]
06:43:34 INFO - 1500990214050 Marionette DEBUG Closed connection 358
06:43:34 INFO - 1500990214051 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49905
06:43:34 INFO - 1500990214052 Marionette DEBUG Closed connection 359
06:43:34 INFO - 1500990214052 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49906
06:43:34 INFO - 1500990214054 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:34 INFO - 1500990214054 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214056 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214059 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d5422c9e-fe7a-445d-b3c9-7f5977b5cb89","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214061 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:43:34 INFO - 1500990214061 Marionette TRACE 360 <- [1,2,null,{}]
06:43:34 INFO - 1500990214062 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:43:34 INFO - 1500990214062 Marionette TRACE 360 <- [1,3,null,{}]
06:43:34 INFO - 1500990214063 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:43:34 INFO - 1500990214064 Marionette TRACE 360 <- [1,4,null,{}]
06:43:34 INFO - 1500990214065 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:43:34 INFO - TEST-START: test_click.py TestClick.test_container_element1500990214068 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:43:34 INFO - 1500990214069 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:43:34 INFO - 1500990214070 Marionette TRACE 360 <- [1,6,null,{}]
06:43:34 INFO - 1500990214074 Marionette DEBUG Closed connection 360
06:43:34 INFO - 1500990214075 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49907
06:43:34 INFO - 1500990214083 Marionette DEBUG Closed connection 361
06:43:34 INFO - 1500990214083 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49908
06:43:34 INFO - 1500990214084 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:34 INFO - 1500990214085 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214086 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214089 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ec278bf2-14bc-47da-b072-d946217256f6","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214091 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:43:34 INFO - 1500990214092 Marionette TRACE 362 <- [1,2,null,{}]
06:43:34 INFO - 1500990214094 Marionette DEBUG Closed connection 362
06:43:34 INFO - 1500990214094 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49909
06:43:34 INFO - 1500990214095 Marionette DEBUG Closed connection 363
06:43:34 INFO - 1500990214096 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49910
06:43:34 INFO - 1500990214097 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:43:34 INFO - 1500990214097 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214099 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214108 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f1fa77a0-beb6-4430-9438-b1becb77326d","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214110 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:43:34 INFO - 1500990214112 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49466/test.html"
06:43:34 INFO - 1500990214115 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49466/test.html"
06:43:34 INFO - 1500990214115 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49466/test.html"
06:43:34 INFO - 1500990214143 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:43:34 INFO - 1500990214148 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:43:34 INFO - 1500990214152 Marionette TRACE 364 <- [1,2,null,{}]
06:43:34 INFO - 1500990214164 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:43:34 INFO - 1500990214166 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"59513497-2254-499f-9204-69be816eb521","ELEMENT":"59513497-2254-499f-9204-69be816eb521"}}]
06:43:34 INFO - 1500990214167 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"59513497-2254-499f-9204-69be816eb521"}]
06:43:34 INFO - 1500990214406 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:43:34 INFO - 1500990214406 Marionette TRACE 364 <- [1,4,null,{}]
06:43:34 INFO - 1500990214407 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"59513497-2254-499f-9204-69be816eb521","name":"selected"}]
06:43:34 INFO - 1500990214412 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:43:34 INFO - 1500990214414 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:43:34 INFO - TEST-END: test_click.py TestClick.test_container_element1500990214417 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:43:34 INFO - 1500990214418 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:43:34 INFO - 1500990214419 Marionette TRACE 364 <- [1,7,null,{}]
06:43:34 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 371ms
06:43:34 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:43:34 INFO - 1500990214421 Marionette DEBUG Closed connection 364
06:43:34 INFO - 1500990214422 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49911
06:43:34 INFO - 1500990214422 Marionette DEBUG Closed connection 365
06:43:34 INFO - 1500990214423 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49912
06:43:34 INFO - 1500990214424 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:34 INFO - 1500990214425 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214427 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214430 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ab4c0c2c-7680-4c77-bc7f-e9fbd7c07402","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214431 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:43:34 INFO - 1500990214432 Marionette TRACE 366 <- [1,2,null,{}]
06:43:34 INFO - 1500990214433 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:43:34 INFO - 1500990214433 Marionette TRACE 366 <- [1,3,null,{}]
06:43:34 INFO - 1500990214434 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:43:34 INFO - 1500990214434 Marionette TRACE 366 <- [1,4,null,{}]
06:43:34 INFO - 1500990214436 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:43:34 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500990214438 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:43:34 INFO - 1500990214439 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:43:34 INFO - 1500990214440 Marionette TRACE 366 <- [1,6,null,{}]
06:43:34 INFO - 1500990214442 Marionette DEBUG Closed connection 366
06:43:34 INFO - 1500990214443 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49913
06:43:34 INFO - 1500990214443 Marionette DEBUG Closed connection 367
06:43:34 INFO - 1500990214444 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49914
06:43:34 INFO - 1500990214445 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:34 INFO - 1500990214446 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214448 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214450 Marionette TRACE 368 <- [1,1,null,{"sessionId":"8842ef5a-3018-4974-9bd4-ec1626cf66c6","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214453 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:43:34 INFO - 1500990214454 Marionette TRACE 368 <- [1,2,null,{}]
06:43:34 INFO - 1500990214455 Marionette DEBUG Closed connection 368
06:43:34 INFO - 1500990214456 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49915
06:43:34 INFO - 1500990214457 Marionette DEBUG Closed connection 369
06:43:34 INFO - 1500990214458 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49916
06:43:34 INFO - 1500990214459 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:43:34 INFO - 1500990214459 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214461 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214463 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3d0adde4-d2ac-4d42-988d-cf113f7eb1a7","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214465 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:43:34 INFO - 1500990214466 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:43:34 INFO - 1500990214472 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:43:34 INFO - 1500990214473 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:43:34 INFO - 1500990214483 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:43:34 INFO - 1500990214490 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:43:34 INFO - 1500990214492 Marionette TRACE 370 <- [1,2,null,{}]
06:43:34 INFO - 1500990214494 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:43:34 INFO - 1500990214496 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a18d4c29-ac15-4447-a541-15ad8fd812ee","ELEMENT":"a18d4c29-ac15-4447-a541-15ad8fd812ee"}}]
06:43:34 INFO - 1500990214497 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a18d4c29-ac15-4447-a541-15ad8fd812ee"}]
06:43:34 INFO - 1500990214738 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:43:34 INFO - 1500990214738 Marionette TRACE 370 <- [1,4,null,{}]
06:43:34 INFO - 1500990214739 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a18d4c29-ac15-4447-a541-15ad8fd812ee","name":"selected"}]
06:43:34 INFO - 1500990214743 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:43:34 INFO - 1500990214745 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:43:34 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500990214747 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:43:34 INFO - 1500990214748 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:43:34 INFO - 1500990214749 Marionette TRACE 370 <- [1,7,null,{}]
06:43:34 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 330ms
06:43:34 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:43:34 INFO - 1500990214751 Marionette DEBUG Closed connection 370
06:43:34 INFO - 1500990214752 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49917
06:43:34 INFO - 1500990214753 Marionette DEBUG Closed connection 371
06:43:34 INFO - 1500990214754 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49918
06:43:34 INFO - 1500990214754 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:34 INFO - 1500990214755 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214757 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214760 Marionette TRACE 372 <- [1,1,null,{"sessionId":"1abc923e-88f2-4d7c-aeec-16f6486d0e85","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214761 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:43:34 INFO - 1500990214762 Marionette TRACE 372 <- [1,2,null,{}]
06:43:34 INFO - 1500990214763 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:43:34 INFO - 1500990214763 Marionette TRACE 372 <- [1,3,null,{}]
06:43:34 INFO - 1500990214764 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:43:34 INFO - 1500990214764 Marionette TRACE 372 <- [1,4,null,{}]
06:43:34 INFO - 1500990214766 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:43:34 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500990214768 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:43:34 INFO - 1500990214769 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:43:34 INFO - 1500990214770 Marionette TRACE 372 <- [1,6,null,{}]
06:43:34 INFO - 1500990214772 Marionette DEBUG Closed connection 372
06:43:34 INFO - 1500990214772 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49919
06:43:34 INFO - 1500990214773 Marionette DEBUG Closed connection 373
06:43:34 INFO - 1500990214774 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49920
06:43:34 INFO - 1500990214775 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:34 INFO - 1500990214775 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214777 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214780 Marionette TRACE 374 <- [1,1,null,{"sessionId":"76ca504b-fe0d-44d8-9ca4-4893d4eed6ed","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214781 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:43:34 INFO - 1500990214782 Marionette TRACE 374 <- [1,2,null,{}]
06:43:34 INFO - 1500990214784 Marionette DEBUG Closed connection 374
06:43:34 INFO - 1500990214785 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49921
06:43:34 INFO - 1500990214786 Marionette DEBUG Closed connection 375
06:43:34 INFO - 1500990214786 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49922
06:43:34 INFO - 1500990214787 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:43:34 INFO - 1500990214788 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:34 INFO - 1500990214789 Marionette DEBUG Register listener.js for window 12
06:43:34 INFO - 1500990214792 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a777b746-d623-4bdb-b325-1f90c94a18c2","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:34 INFO - 1500990214794 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:43:34 INFO - 1500990214795 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:43:34 INFO - 1500990214798 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:43:34 INFO - 1500990214798 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:43:34 INFO - 1500990214817 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:43:34 INFO - 1500990214825 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:43:34 INFO - 1500990214836 Marionette TRACE 376 <- [1,2,null,{}]
06:43:34 INFO - 1500990214837 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:43:34 INFO - 1500990214840 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"912fe6e9-a101-4553-b51c-b34c8c6b3cda","ELEMENT":"912fe6e9-a101-4553-b51c-b34c8c6b3cda"}}]
06:43:34 INFO - 1500990214840 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"912fe6e9-a101-4553-b51c-b34c8c6b3cda"}]
06:43:35 INFO - 1500990215075 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:43:35 INFO - 1500990215075 Marionette TRACE 376 <- [1,4,null,{}]
06:43:35 INFO - 1500990215077 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:43:35 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500990215080 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:43:35 INFO - 1500990215080 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:43:35 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 332ms
06:43:35 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:43:35 INFO - 1500990215082 Marionette TRACE 376 <- [1,6,null,{}]
06:43:35 INFO - 1500990215083 Marionette DEBUG Closed connection 376
06:43:35 INFO - 1500990215084 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49923
06:43:35 INFO - 1500990215085 Marionette DEBUG Closed connection 377
06:43:35 INFO - 1500990215086 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49924
06:43:35 INFO - 1500990215087 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:35 INFO - 1500990215087 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215089 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215092 Marionette TRACE 378 <- [1,1,null,{"sessionId":"44aae4bb-dfec-4c39-9c99-0cef4eadda4c","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215094 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:43:35 INFO - 1500990215094 Marionette TRACE 378 <- [1,2,null,{}]
06:43:35 INFO - 1500990215095 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:43:35 INFO - 1500990215095 Marionette TRACE 378 <- [1,3,null,{}]
06:43:35 INFO - 1500990215096 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:43:35 INFO - 1500990215097 Marionette TRACE 378 <- [1,4,null,{}]
06:43:35 INFO - 1500990215098 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:43:35 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500990215100 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:43:35 INFO - 1500990215101 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:43:35 INFO - 1500990215102 Marionette TRACE 378 <- [1,6,null,{}]
06:43:35 INFO - 1500990215104 Marionette DEBUG Closed connection 378
06:43:35 INFO - 1500990215105 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49925
06:43:35 INFO - 1500990215105 Marionette DEBUG Closed connection 379
06:43:35 INFO - 1500990215106 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49926
06:43:35 INFO - 1500990215107 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:35 INFO - 1500990215108 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215109 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215112 Marionette TRACE 380 <- [1,1,null,{"sessionId":"075d76e1-814e-4c58-a2b9-260d896dddb8","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215114 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:43:35 INFO - 1500990215115 Marionette TRACE 380 <- [1,2,null,{}]
06:43:35 INFO - 1500990215117 Marionette DEBUG Closed connection 380
06:43:35 INFO - 1500990215117 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49927
06:43:35 INFO - 1500990215118 Marionette DEBUG Closed connection 381
06:43:35 INFO - 1500990215119 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49928
06:43:35 INFO - 1500990215120 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:43:35 INFO - 1500990215120 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215122 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215125 Marionette TRACE 382 <- [1,1,null,{"sessionId":"1ec1fb79-43b0-46a0-b282-81a2238e31bf","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215126 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:43:35 INFO - 1500990215128 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:43:35 INFO - 1500990215129 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:43:35 INFO - 1500990215130 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:43:35 INFO - 1500990215146 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:43:35 INFO - 1500990215148 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:43:35 INFO - 1500990215151 Marionette TRACE 382 <- [1,2,null,{}]
06:43:35 INFO - 1500990215159 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:43:35 INFO - 1500990215161 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3cdda6d2-13fc-4396-8cab-3026fc65fc1a","ELEMENT":"3cdda6d2-13fc-4396-8cab-3026fc65fc1a"}}]
06:43:35 INFO - 1500990215162 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"3cdda6d2-13fc-4396-8cab-3026fc65fc1a"}]
06:43:35 INFO - 1500990215401 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:43:35 INFO - 1500990215402 Marionette TRACE 382 <- [1,4,null,{}]
06:43:35 INFO - 1500990215404 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:43:35 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500990215406 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:43:35 INFO - 1500990215407 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:43:35 INFO - 1500990215408 Marionette TRACE 382 <- [1,6,null,{}]
06:43:35 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 326ms
06:43:35 INFO - TEST-START | test_click.py TestClick.test_input_file
06:43:35 INFO - 1500990215410 Marionette DEBUG Closed connection 382
06:43:35 INFO - 1500990215411 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49929
06:43:35 INFO - 1500990215412 Marionette DEBUG Closed connection 383
06:43:35 INFO - 1500990215412 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49930
06:43:35 INFO - 1500990215413 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:35 INFO - 1500990215414 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215416 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215419 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b13377df-3515-4233-85cc-86e10c57185a","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215421 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:43:35 INFO - 1500990215421 Marionette TRACE 384 <- [1,2,null,{}]
06:43:35 INFO - 1500990215422 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:43:35 INFO - 1500990215422 Marionette TRACE 384 <- [1,3,null,{}]
06:43:35 INFO - 1500990215423 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:43:35 INFO - 1500990215424 Marionette TRACE 384 <- [1,4,null,{}]
06:43:35 INFO - 1500990215425 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:43:35 INFO - TEST-START: test_click.py TestClick.test_input_file1500990215428 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:43:35 INFO - 1500990215429 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:43:35 INFO - 1500990215430 Marionette TRACE 384 <- [1,6,null,{}]
06:43:35 INFO - 1500990215431 Marionette DEBUG Closed connection 384
06:43:35 INFO - 1500990215432 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49931
06:43:35 INFO - 1500990215433 Marionette DEBUG Closed connection 385
06:43:35 INFO - 1500990215434 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49932
06:43:35 INFO - 1500990215435 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:35 INFO - 1500990215435 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215437 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215440 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b37094d6-eebb-4cbd-9f7c-a0d2af845f97","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215441 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:43:35 INFO - 1500990215442 Marionette TRACE 386 <- [1,2,null,{}]
06:43:35 INFO - 1500990215444 Marionette DEBUG Closed connection 386
06:43:35 INFO - 1500990215445 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49933
06:43:35 INFO - 1500990215446 Marionette DEBUG Closed connection 387
06:43:35 INFO - 1500990215446 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49934
06:43:35 INFO - 1500990215447 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:43:35 INFO - 1500990215448 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215449 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215452 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9cf7e6ce-c880-42cb-8e16-87cdc9c7a4be","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215454 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:43:35 INFO - 1500990215455 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:43:35 INFO - 1500990215457 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:43:35 INFO - 1500990215457 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:43:35 INFO - 1500990215474 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:43:35 INFO - 1500990215475 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:43:35 INFO - 1500990215478 Marionette TRACE 388 <- [1,2,null,{}]
06:43:35 INFO - 1500990215479 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:43:35 INFO - 1500990215481 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"626ed29b-d1d6-4208-9ead-e213f4efcf5c","ELEMENT":"626ed29b-d1d6-4208-9ead-e213f4efcf5c"}}]
06:43:35 INFO - 1500990215482 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"626ed29b-d1d6-4208-9ead-e213f4efcf5c"}]
06:43:35 INFO - 1500990215519 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:43:35 INFO - 1500990215521 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:43:35 INFO - TEST-END: test_click.py TestClick.test_input_file1500990215523 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:43:35 INFO - 1500990215525 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:43:35 INFO - 1500990215526 Marionette TRACE 388 <- [1,6,null,{}]
06:43:35 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 117ms
06:43:35 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:43:35 INFO - 1500990215528 Marionette DEBUG Closed connection 388
06:43:35 INFO - 1500990215529 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49935
06:43:35 INFO - 1500990215529 Marionette DEBUG Closed connection 389
06:43:35 INFO - 1500990215530 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49936
06:43:35 INFO - 1500990215531 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:35 INFO - 1500990215531 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215533 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215536 Marionette TRACE 390 <- [1,1,null,{"sessionId":"939cb9e0-b851-406d-8551-53a94c7739d1","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215538 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:43:35 INFO - 1500990215539 Marionette TRACE 390 <- [1,2,null,{}]
06:43:35 INFO - 1500990215540 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:43:35 INFO - 1500990215540 Marionette TRACE 390 <- [1,3,null,{}]
06:43:35 INFO - 1500990215541 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:43:35 INFO - 1500990215542 Marionette TRACE 390 <- [1,4,null,{}]
06:43:35 INFO - 1500990215543 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:43:35 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500990215546 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:43:35 INFO - 1500990215547 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:43:35 INFO - 1500990215548 Marionette TRACE 390 <- [1,6,null,{}]
06:43:35 INFO - 1500990215550 Marionette DEBUG Closed connection 390
06:43:35 INFO - 1500990215550 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49937
06:43:35 INFO - 1500990215551 Marionette DEBUG Closed connection 391
06:43:35 INFO - 1500990215552 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49938
06:43:35 INFO - 1500990215553 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:35 INFO - 1500990215553 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215555 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215558 Marionette TRACE 392 <- [1,1,null,{"sessionId":"e02aff40-6778-4c3d-8596-221308de78b6","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215559 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:43:35 INFO - 1500990215560 Marionette TRACE 392 <- [1,2,null,{}]
06:43:35 INFO - 1500990215562 Marionette DEBUG Closed connection 392
06:43:35 INFO - 1500990215563 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49939
06:43:35 INFO - 1500990215563 Marionette DEBUG Closed connection 393
06:43:35 INFO - 1500990215564 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49940
06:43:35 INFO - 1500990215565 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:43:35 INFO - 1500990215566 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215567 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215570 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c87de186-b7f0-43ae-beb7-5c8f93e68555","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215572 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:43:35 INFO - 1500990215573 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:43:35 INFO - 1500990215575 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:43:35 INFO - 1500990215575 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:43:35 INFO - 1500990215595 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:43:35 INFO - 1500990215596 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:43:35 INFO - 1500990215599 Marionette TRACE 394 <- [1,2,null,{}]
06:43:35 INFO - 1500990215600 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:43:35 INFO - 1500990215608 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf1f5fa7-5bb0-4a25-9b3e-2af0b2d0e5f9","ELEMENT":"bf1f5fa7-5bb0-4a25-9b3e-2af0b2d0e5f9"}}]
06:43:35 INFO - 1500990215609 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:43:35 INFO - 1500990215611 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"869c7d1b-10ce-4a40-8af7-7e16d90a131b","ELEMENT":"869c7d1b-10ce-4a40-8af7-7e16d90a131b"}}]
06:43:35 INFO - 1500990215612 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"bf1f5fa7-5bb0-4a25-9b3e-2af0b2d0e5f9"}]
06:43:35 INFO - 1500990215848 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:43:35 INFO - 1500990215848 Marionette TRACE 394 <- [1,5,null,{}]
06:43:35 INFO - 1500990215849 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"869c7d1b-10ce-4a40-8af7-7e16d90a131b"}]
06:43:35 INFO - 1500990215863 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:43:35 INFO - 1500990215865 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:43:35 INFO - 1500990215867 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:43:35 INFO - 1500990215869 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:43:35 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500990215871 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:43:35 INFO - 1500990215872 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:43:35 INFO - 1500990215873 Marionette TRACE 394 <- [1,9,null,{}]
06:43:35 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 347ms
06:43:35 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:43:35 INFO - 1500990215875 Marionette DEBUG Closed connection 394
06:43:35 INFO - 1500990215876 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49941
06:43:35 INFO - 1500990215876 Marionette DEBUG Closed connection 395
06:43:35 INFO - 1500990215877 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49942
06:43:35 INFO - 1500990215878 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:35 INFO - 1500990215879 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215881 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215883 Marionette TRACE 396 <- [1,1,null,{"sessionId":"61e265f2-8027-4264-9645-9942ff5f1800","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215885 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:43:35 INFO - 1500990215886 Marionette TRACE 396 <- [1,2,null,{}]
06:43:35 INFO - 1500990215886 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:43:35 INFO - 1500990215887 Marionette TRACE 396 <- [1,3,null,{}]
06:43:35 INFO - 1500990215888 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:43:35 INFO - 1500990215888 Marionette TRACE 396 <- [1,4,null,{}]
06:43:35 INFO - 1500990215890 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:43:35 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500990215892 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:43:35 INFO - 1500990215893 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:43:35 INFO - 1500990215894 Marionette TRACE 396 <- [1,6,null,{}]
06:43:35 INFO - 1500990215895 Marionette DEBUG Closed connection 396
06:43:35 INFO - 1500990215896 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49943
06:43:35 INFO - 1500990215897 Marionette DEBUG Closed connection 397
06:43:35 INFO - 1500990215897 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49944
06:43:35 INFO - 1500990215898 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:43:35 INFO - 1500990215899 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215901 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215903 Marionette TRACE 398 <- [1,1,null,{"sessionId":"52e77235-5ad5-48b9-9e96-2769ed6c8cdc","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215905 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:43:35 INFO - 1500990215906 Marionette TRACE 398 <- [1,2,null,{}]
06:43:35 INFO - 1500990215908 Marionette DEBUG Closed connection 398
06:43:35 INFO - 1500990215908 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49945
06:43:35 INFO - 1500990215909 Marionette DEBUG Closed connection 399
06:43:35 INFO - 1500990215910 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49946
06:43:35 INFO - 1500990215911 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:43:35 INFO - 1500990215911 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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}
06:43:35 INFO - 1500990215913 Marionette DEBUG Register listener.js for window 12
06:43:35 INFO - 1500990215915 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b6bf05ce-0f3a-4b75-8272-db6bdd612a89","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":1424,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjetanh.mozrunner","moz:accessibilityChecks":false}}]
06:43:35 INFO - 1500990215917 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:43:35 INFO - 1500990215919 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:43:35 INFO - 1500990215921 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:43:35 INFO - 1500990215921 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:43:35 INFO - 1500990215933 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:43:35 INFO - 1500990215936 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:43:35 INFO - 1500990215945 Marionette TRACE 400 <- [1,2,null,{}]
06:43:35 INFO - 1500990215947 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:43:35 INFO - 1500990215950 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5f8d5755-e2b9-4dc7-b36e-6a5d2b523c43","ELEMENT":"5f8d5755-e2b9-4dc7-b36e-6a5d2b523c43"}}]
06:43:35 INFO - 1500990215951 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5f8d5755-e2b9-4dc7-b36e-6a5d2b523c43"}]
06:43:35 INFO - 1500990215978 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:43:35 INFO - 1500990215979 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5f8d5755-e2b9-4dc7-b36e-6a5d2b523c43"}]
06:43:35 INFO - 1500990215993 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element