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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:11:10 INFO - 1499101870948 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}]
10:11:10 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499101870951 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:11:10 INFO - 1499101870953 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:11:10 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 155ms
10:11:10 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:11:10 INFO - 1499101870954 Marionette TRACE 352 <- [1,6,null,{}]
10:11:10 INFO - 1499101870956 Marionette DEBUG Closed connection 352
10:11:10 INFO - 1499101870957 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49815
10:11:10 INFO - 1499101870958 Marionette DEBUG Closed connection 353
10:11:10 INFO - 1499101870958 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49816
10:11:10 INFO - 1499101870960 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:10 INFO - 1499101870960 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:10 INFO - 1499101870963 Marionette DEBUG Register listener.js for window 4294967297
10:11:10 INFO - 1499101870967 Marionette TRACE 354 <- [1,1,null,{"sessionId":"45689724-38ac-42bd-bfb5-937c470490e1","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:10 INFO - 1499101870968 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:11:10 INFO - 1499101870969 Marionette TRACE 354 <- [1,2,null,{}]
10:11:10 INFO - 1499101870970 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:11:10 INFO - 1499101870970 Marionette TRACE 354 <- [1,3,null,{}]
10:11:10 INFO - 1499101870971 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:11:10 INFO - 1499101870972 Marionette TRACE 354 <- [1,4,null,{}]
10:11:10 INFO - 1499101870973 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}]
10:11:10 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499101870976 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:11:10 INFO - 1499101870977 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:11:10 INFO - 1499101870979 Marionette TRACE 354 <- [1,6,null,{}]
10:11:10 INFO - 1499101870981 Marionette DEBUG Closed connection 354
10:11:10 INFO - 1499101870981 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49817
10:11:10 INFO - 1499101870982 Marionette DEBUG Closed connection 355
10:11:10 INFO - 1499101870983 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49818
10:11:10 INFO - 1499101870984 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:10 INFO - 1499101870985 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:10 INFO - 1499101870988 Marionette DEBUG Register listener.js for window 4294967297
10:11:10 INFO - 1499101870991 Marionette TRACE 356 <- [1,1,null,{"sessionId":"373dd627-552a-4263-9e83-e4e3fb2d38c1","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:10 INFO - 1499101870992 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:11:11 INFO - 1499101870994 Marionette TRACE 356 <- [1,2,null,{}]
10:11:11 INFO - 1499101870995 Marionette DEBUG Closed connection 356
10:11:11 INFO - 1499101870996 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49819
10:11:11 INFO - 1499101870997 Marionette DEBUG Closed connection 357
10:11:11 INFO - 1499101870998 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49820
10:11:11 INFO - 1499101870999 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:11:11 INFO - 1499101871000 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871003 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871006 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0d44ec3a-8de6-4628-978d-0048714114da","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871008 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49370/clicks.html"}]
10:11:11 INFO - 1499101871010 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49370/hidden.html"
10:11:11 INFO - 1499101871023 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49370/hidden.html"
10:11:11 INFO - 1499101871023 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49370/hidden.html"
10:11:11 INFO - 1499101871031 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49370/clicks.html"
10:11:11 INFO - 1499101871038 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49370/clicks.html"
10:11:11 INFO - 1499101871040 Marionette TRACE 358 <- [1,2,null,{}]
10:11:11 INFO - 1499101871045 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:11:11 INFO - 1499101871049 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c1b6024c-a976-44ec-993e-ba432aa78c3c","ELEMENT":"c1b6024c-a976-44ec-993e-ba432aa78c3c"}}]
10:11:11 INFO - 1499101871050 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"c1b6024c-a976-44ec-993e-ba432aa78c3c"}]
10:11:11 INFO - 1499101871096 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49370/clicks.html"
10:11:11 INFO - 1499101871106 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49370/clicks.html"
10:11:11 INFO - 1499101871107 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49370/clicks.html"
10:11:11 INFO - 1499101871120 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49370/test.html"
10:11:11 INFO - 1499101871124 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49370/test.html"
10:11:11 INFO - 1499101871125 Marionette TRACE 358 <- [1,4,null,{}]
10:11:11 INFO - 1499101871130 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:11:11 INFO - 1499101871133 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"07f44eee-17ec-47e4-861f-3e93ef99ab43","ELEMENT":"07f44eee-17ec-47e4-861f-3e93ef99ab43"}}]
10:11:11 INFO - 1499101871139 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:11:11 INFO - 1499101871141 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:11:11 INFO - 1499101871144 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}]
10:11:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 196ms
10:11:11 INFO - TEST-START | test_click.py TestClick.test_container_element
10:11:11 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499101871148 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:11:11 INFO - 1499101871149 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:11:11 INFO - 1499101871151 Marionette TRACE 358 <- [1,8,null,{}]
10:11:11 INFO - 1499101871153 Marionette DEBUG Closed connection 358
10:11:11 INFO - 1499101871154 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49823
10:11:11 INFO - 1499101871155 Marionette DEBUG Closed connection 359
10:11:11 INFO - 1499101871155 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49824
10:11:11 INFO - 1499101871157 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:11 INFO - 1499101871158 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871166 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871170 Marionette TRACE 360 <- [1,1,null,{"sessionId":"b00db785-b3d2-452b-b0b9-b7f8021d00a4","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871172 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:11:11 INFO - 1499101871172 Marionette TRACE 360 <- [1,2,null,{}]
10:11:11 INFO - 1499101871173 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:11:11 INFO - 1499101871174 Marionette TRACE 360 <- [1,3,null,{}]
10:11:11 INFO - 1499101871175 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:11:11 INFO - 1499101871175 Marionette TRACE 360 <- [1,4,null,{}]
10:11:11 INFO - 1499101871177 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}]
10:11:11 INFO - TEST-START: test_click.py TestClick.test_container_element1499101871180 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:11:11 INFO - 1499101871182 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:11:11 INFO - 1499101871183 Marionette TRACE 360 <- [1,6,null,{}]
10:11:11 INFO - 1499101871185 Marionette DEBUG Closed connection 360
10:11:11 INFO - 1499101871186 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49825
10:11:11 INFO - 1499101871187 Marionette DEBUG Closed connection 361
10:11:11 INFO - 1499101871188 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49826
10:11:11 INFO - 1499101871189 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:11 INFO - 1499101871189 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871192 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871196 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e90d78db-abf2-4c54-a637-8f4268d908c3","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871197 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:11:11 INFO - 1499101871198 Marionette TRACE 362 <- [1,2,null,{}]
10:11:11 INFO - 1499101871200 Marionette DEBUG Closed connection 362
10:11:11 INFO - 1499101871201 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49827
10:11:11 INFO - 1499101871202 Marionette DEBUG Closed connection 363
10:11:11 INFO - 1499101871203 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49828
10:11:11 INFO - 1499101871204 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:11:11 INFO - 1499101871204 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871207 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871211 Marionette TRACE 364 <- [1,1,null,{"sessionId":"2606317c-18a3-4a45-8dd7-2209067caa71","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871212 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"}]
10:11:11 INFO - 1499101871214 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49370/test.html"
10:11:11 INFO - 1499101871216 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49370/test.html"
10:11:11 INFO - 1499101871217 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49370/test.html"
10:11:11 INFO - 1499101871224 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"
10:11:11 INFO - 1499101871228 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"
10:11:11 INFO - 1499101871241 Marionette TRACE 364 <- [1,2,null,{}]
10:11:11 INFO - 1499101871247 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:11:11 INFO - 1499101871250 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"705324bb-d439-412f-9a66-410bd79d247a","ELEMENT":"705324bb-d439-412f-9a66-410bd79d247a"}}]
10:11:11 INFO - 1499101871256 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"705324bb-d439-412f-9a66-410bd79d247a"}]
10:11:11 INFO - 1499101871500 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:11:11 INFO - 1499101871501 Marionette TRACE 364 <- [1,4,null,{}]
10:11:11 INFO - 1499101871502 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"705324bb-d439-412f-9a66-410bd79d247a","name":"selected"}]
10:11:11 INFO - 1499101871507 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:11:11 INFO - 1499101871509 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}]
10:11:11 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 364ms
10:11:11 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:11:11 INFO - TEST-END: test_click.py TestClick.test_container_element1499101871513 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:11:11 INFO - 1499101871514 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:11:11 INFO - 1499101871515 Marionette TRACE 364 <- [1,7,null,{}]
10:11:11 INFO - 1499101871518 Marionette DEBUG Closed connection 364
10:11:11 INFO - 1499101871519 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49829
10:11:11 INFO - 1499101871520 Marionette DEBUG Closed connection 365
10:11:11 INFO - 1499101871521 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49830
10:11:11 INFO - 1499101871522 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:11 INFO - 1499101871523 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871526 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871529 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4832ab32-58d5-45ea-936b-312a8f2b2b86","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871530 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:11:11 INFO - 1499101871531 Marionette TRACE 366 <- [1,2,null,{}]
10:11:11 INFO - 1499101871532 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:11:11 INFO - 1499101871532 Marionette TRACE 366 <- [1,3,null,{}]
10:11:11 INFO - 1499101871533 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:11:11 INFO - 1499101871534 Marionette TRACE 366 <- [1,4,null,{}]
10:11:11 INFO - 1499101871536 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}]
10:11:11 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499101871539 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:11:11 INFO - 1499101871540 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:11:11 INFO - 1499101871541 Marionette TRACE 366 <- [1,6,null,{}]
10:11:11 INFO - 1499101871543 Marionette DEBUG Closed connection 366
10:11:11 INFO - 1499101871544 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49831
10:11:11 INFO - 1499101871545 Marionette DEBUG Closed connection 367
10:11:11 INFO - 1499101871546 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49832
10:11:11 INFO - 1499101871547 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:11 INFO - 1499101871547 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871550 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871554 Marionette TRACE 368 <- [1,1,null,{"sessionId":"00635d1c-608b-4518-982e-3b79cb573c98","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871555 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:11:11 INFO - 1499101871556 Marionette TRACE 368 <- [1,2,null,{}]
10:11:11 INFO - 1499101871558 Marionette DEBUG Closed connection 368
10:11:11 INFO - 1499101871559 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49833
10:11:11 INFO - 1499101871560 Marionette DEBUG Closed connection 369
10:11:11 INFO - 1499101871561 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49834
10:11:11 INFO - 1499101871562 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:11:11 INFO - 1499101871562 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871565 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871568 Marionette TRACE 370 <- [1,1,null,{"sessionId":"2cb92a68-3967-4b16-bad4-a2fd5cbbea42","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871570 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"}]
10:11:11 INFO - 1499101871572 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"
10:11:11 INFO - 1499101871574 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"
10:11:11 INFO - 1499101871575 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"
10:11:11 INFO - 1499101871581 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"
10:11:11 INFO - 1499101871585 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"
10:11:11 INFO - 1499101871593 Marionette TRACE 370 <- [1,2,null,{}]
10:11:11 INFO - 1499101871598 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:11:11 INFO - 1499101871605 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"970efb7f-a8b1-42e9-ae2e-b953ed499033","ELEMENT":"970efb7f-a8b1-42e9-ae2e-b953ed499033"}}]
10:11:11 INFO - 1499101871607 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"970efb7f-a8b1-42e9-ae2e-b953ed499033"}]
10:11:11 INFO - 1499101871861 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:11:11 INFO - 1499101871862 Marionette TRACE 370 <- [1,4,null,{}]
10:11:11 INFO - 1499101871863 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"970efb7f-a8b1-42e9-ae2e-b953ed499033","name":"selected"}]
10:11:11 INFO - 1499101871868 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:11:11 INFO - 1499101871870 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}]
10:11:11 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499101871873 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:11:11 INFO - 1499101871874 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:11:11 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 360ms
10:11:11 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:11:11 INFO - 1499101871876 Marionette TRACE 370 <- [1,7,null,{}]
10:11:11 INFO - 1499101871878 Marionette DEBUG Closed connection 370
10:11:11 INFO - 1499101871878 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49835
10:11:11 INFO - 1499101871879 Marionette DEBUG Closed connection 371
10:11:11 INFO - 1499101871880 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49836
10:11:11 INFO - 1499101871881 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:11 INFO - 1499101871882 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871885 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871888 Marionette TRACE 372 <- [1,1,null,{"sessionId":"51e21f3b-e687-4ae2-b6a2-c0d096e597cc","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871889 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:11:11 INFO - 1499101871890 Marionette TRACE 372 <- [1,2,null,{}]
10:11:11 INFO - 1499101871891 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:11:11 INFO - 1499101871892 Marionette TRACE 372 <- [1,3,null,{}]
10:11:11 INFO - 1499101871892 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:11:11 INFO - 1499101871893 Marionette TRACE 372 <- [1,4,null,{}]
10:11:11 INFO - 1499101871895 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}]
10:11:11 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499101871898 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:11:11 INFO - 1499101871899 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:11:11 INFO - 1499101871900 Marionette TRACE 372 <- [1,6,null,{}]
10:11:11 INFO - 1499101871902 Marionette DEBUG Closed connection 372
10:11:11 INFO - 1499101871903 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49837
10:11:11 INFO - 1499101871904 Marionette DEBUG Closed connection 373
10:11:11 INFO - 1499101871904 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49838
10:11:11 INFO - 1499101871905 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:11 INFO - 1499101871906 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871909 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871913 Marionette TRACE 374 <- [1,1,null,{"sessionId":"25720863-6892-482f-8aa0-64a9b971b258","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871914 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:11:11 INFO - 1499101871915 Marionette TRACE 374 <- [1,2,null,{}]
10:11:11 INFO - 1499101871918 Marionette DEBUG Closed connection 374
10:11:11 INFO - 1499101871919 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49839
10:11:11 INFO - 1499101871920 Marionette DEBUG Closed connection 375
10:11:11 INFO - 1499101871921 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49840
10:11:11 INFO - 1499101871922 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:11:11 INFO - 1499101871923 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:11 INFO - 1499101871925 Marionette DEBUG Register listener.js for window 4294967297
10:11:11 INFO - 1499101871929 Marionette TRACE 376 <- [1,1,null,{"sessionId":"7ad2b419-2517-4065-a753-1c9f71be8110","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:11 INFO - 1499101871930 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"}]
10:11:11 INFO - 1499101871932 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"
10:11:11 INFO - 1499101871934 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"
10:11:11 INFO - 1499101871935 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"
10:11:11 INFO - 1499101871942 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"
10:11:11 INFO - 1499101871944 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"
10:11:11 INFO - 1499101871948 Marionette TRACE 376 <- [1,2,null,{}]
10:11:11 INFO - 1499101871959 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:11:11 INFO - 1499101871963 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b0267a13-ad7e-4a5c-bd6d-b1dfc6747664","ELEMENT":"b0267a13-ad7e-4a5c-bd6d-b1dfc6747664"}}]
10:11:11 INFO - 1499101871964 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b0267a13-ad7e-4a5c-bd6d-b1dfc6747664"}]
10:11:12 INFO - 1499101872206 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:11:12 INFO - 1499101872207 Marionette TRACE 376 <- [1,4,null,{}]
10:11:12 INFO - 1499101872209 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}]
10:11:12 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499101872213 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:11:12 INFO - 1499101872214 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:11:12 INFO - 1499101872216 Marionette TRACE 376 <- [1,6,null,{}]
10:11:12 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 339ms
10:11:12 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:11:12 INFO - 1499101872217 Marionette DEBUG Closed connection 376
10:11:12 INFO - 1499101872218 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49841
10:11:12 INFO - 1499101872219 Marionette DEBUG Closed connection 377
10:11:12 INFO - 1499101872220 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49842
10:11:12 INFO - 1499101872221 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:12 INFO - 1499101872222 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872225 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872228 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e9ce8cb5-5c2e-4357-8ee9-c27c966455e4","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872230 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:11:12 INFO - 1499101872230 Marionette TRACE 378 <- [1,2,null,{}]
10:11:12 INFO - 1499101872231 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:11:12 INFO - 1499101872232 Marionette TRACE 378 <- [1,3,null,{}]
10:11:12 INFO - 1499101872233 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:11:12 INFO - 1499101872233 Marionette TRACE 378 <- [1,4,null,{}]
10:11:12 INFO - 1499101872235 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}]
10:11:12 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499101872238 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:11:12 INFO - 1499101872239 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:11:12 INFO - 1499101872240 Marionette TRACE 378 <- [1,6,null,{}]
10:11:12 INFO - 1499101872242 Marionette DEBUG Closed connection 378
10:11:12 INFO - 1499101872243 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49843
10:11:12 INFO - 1499101872244 Marionette DEBUG Closed connection 379
10:11:12 INFO - 1499101872244 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49844
10:11:12 INFO - 1499101872246 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:12 INFO - 1499101872246 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872249 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872262 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a20084f6-f7be-4a16-bbf7-e25af076c2cb","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872263 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:11:12 INFO - 1499101872265 Marionette TRACE 380 <- [1,2,null,{}]
10:11:12 INFO - 1499101872266 Marionette DEBUG Closed connection 380
10:11:12 INFO - 1499101872267 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49845
10:11:12 INFO - 1499101872268 Marionette DEBUG Closed connection 381
10:11:12 INFO - 1499101872269 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49846
10:11:12 INFO - 1499101872270 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:11:12 INFO - 1499101872271 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872274 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872277 Marionette TRACE 382 <- [1,1,null,{"sessionId":"953e8d0b-af36-42b8-bbe2-41e455a4417f","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872278 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"}]
10:11:12 INFO - 1499101872280 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"
10:11:12 INFO - 1499101872282 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"
10:11:12 INFO - 1499101872282 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"
10:11:12 INFO - 1499101872290 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"
10:11:12 INFO - 1499101872294 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"
10:11:12 INFO - 1499101872299 Marionette TRACE 382 <- [1,2,null,{}]
10:11:12 INFO - 1499101872309 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:11:12 INFO - 1499101872313 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f8e36e5-e464-41cc-a1e0-63dead822e3b","ELEMENT":"6f8e36e5-e464-41cc-a1e0-63dead822e3b"}}]
10:11:12 INFO - 1499101872314 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"6f8e36e5-e464-41cc-a1e0-63dead822e3b"}]
10:11:12 INFO - 1499101872565 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:11:12 INFO - 1499101872566 Marionette TRACE 382 <- [1,4,null,{}]
10:11:12 INFO - 1499101872568 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}]
10:11:12 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499101872571 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:11:12 INFO - 1499101872572 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:11:12 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 358ms
10:11:12 INFO - TEST-START | test_click.py TestClick.test_input_file
10:11:12 INFO - 1499101872574 Marionette TRACE 382 <- [1,6,null,{}]
10:11:12 INFO - 1499101872575 Marionette DEBUG Closed connection 382
10:11:12 INFO - 1499101872576 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49847
10:11:12 INFO - 1499101872577 Marionette DEBUG Closed connection 383
10:11:12 INFO - 1499101872578 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49848
10:11:12 INFO - 1499101872579 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:12 INFO - 1499101872579 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872582 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872586 Marionette TRACE 384 <- [1,1,null,{"sessionId":"765ee860-db5c-480a-a7d0-428272b6ddbc","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872587 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:11:12 INFO - 1499101872587 Marionette TRACE 384 <- [1,2,null,{}]
10:11:12 INFO - 1499101872588 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:11:12 INFO - 1499101872589 Marionette TRACE 384 <- [1,3,null,{}]
10:11:12 INFO - 1499101872590 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:11:12 INFO - 1499101872591 Marionette TRACE 384 <- [1,4,null,{}]
10:11:12 INFO - 1499101872592 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}]
10:11:12 INFO - TEST-START: test_click.py TestClick.test_input_file1499101872595 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:11:12 INFO - 1499101872596 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:11:12 INFO - 1499101872598 Marionette TRACE 384 <- [1,6,null,{}]
10:11:12 INFO - 1499101872600 Marionette DEBUG Closed connection 384
10:11:12 INFO - 1499101872601 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49849
10:11:12 INFO - 1499101872601 Marionette DEBUG Closed connection 385
10:11:12 INFO - 1499101872602 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49850
10:11:12 INFO - 1499101872604 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:12 INFO - 1499101872604 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872607 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872610 Marionette TRACE 386 <- [1,1,null,{"sessionId":"ed453b88-171b-45e8-8cf8-fdf776e53ff7","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872612 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:11:12 INFO - 1499101872613 Marionette TRACE 386 <- [1,2,null,{}]
10:11:12 INFO - 1499101872615 Marionette DEBUG Closed connection 386
10:11:12 INFO - 1499101872615 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49851
10:11:12 INFO - 1499101872616 Marionette DEBUG Closed connection 387
10:11:12 INFO - 1499101872618 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49852
10:11:12 INFO - 1499101872619 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:11:12 INFO - 1499101872619 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872622 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872625 Marionette TRACE 388 <- [1,1,null,{"sessionId":"d41ac17a-58a4-4f8b-8d72-322fff051039","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872627 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:11:12 INFO - 1499101872629 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"
10:11:12 INFO - 1499101872631 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"
10:11:12 INFO - 1499101872632 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"
10:11:12 INFO - 1499101872639 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:11:12 INFO - 1499101872644 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:11:12 INFO - 1499101872647 Marionette TRACE 388 <- [1,2,null,{}]
10:11:12 INFO - 1499101872650 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:11:12 INFO - 1499101872663 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe7e1c14-73ec-4e16-bdf7-30b5c3e73e82","ELEMENT":"fe7e1c14-73ec-4e16-bdf7-30b5c3e73e82"}}]
10:11:12 INFO - 1499101872665 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"fe7e1c14-73ec-4e16-bdf7-30b5c3e73e82"}]
10:11:12 INFO - 1499101872694 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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:11:12 INFO - 1499101872696 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}]
10:11:12 INFO - TEST-END: test_click.py TestClick.test_input_file1499101872699 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:11:12 INFO - 1499101872701 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:11:12 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 129ms
10:11:12 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:11:12 INFO - 1499101872702 Marionette TRACE 388 <- [1,6,null,{}]
10:11:12 INFO - 1499101872704 Marionette DEBUG Closed connection 388
10:11:12 INFO - 1499101872705 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49853
10:11:12 INFO - 1499101872706 Marionette DEBUG Closed connection 389
10:11:12 INFO - 1499101872706 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49854
10:11:12 INFO - 1499101872708 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:12 INFO - 1499101872708 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872711 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872714 Marionette TRACE 390 <- [1,1,null,{"sessionId":"89d40b23-d62a-4637-aa75-b91086330ba4","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872716 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:11:12 INFO - 1499101872716 Marionette TRACE 390 <- [1,2,null,{}]
10:11:12 INFO - 1499101872717 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:11:12 INFO - 1499101872718 Marionette TRACE 390 <- [1,3,null,{}]
10:11:12 INFO - 1499101872719 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:11:12 INFO - 1499101872719 Marionette TRACE 390 <- [1,4,null,{}]
10:11:12 INFO - 1499101872721 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}]
10:11:12 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499101872724 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:11:12 INFO - 1499101872725 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:11:12 INFO - 1499101872727 Marionette TRACE 390 <- [1,6,null,{}]
10:11:12 INFO - 1499101872728 Marionette DEBUG Closed connection 390
10:11:12 INFO - 1499101872729 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49855
10:11:12 INFO - 1499101872730 Marionette DEBUG Closed connection 391
10:11:12 INFO - 1499101872731 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49856
10:11:12 INFO - 1499101872732 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:12 INFO - 1499101872732 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872735 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872739 Marionette TRACE 392 <- [1,1,null,{"sessionId":"963fbb35-4086-4044-bfe9-921395ae047e","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872740 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:11:12 INFO - 1499101872741 Marionette TRACE 392 <- [1,2,null,{}]
10:11:12 INFO - 1499101872743 Marionette DEBUG Closed connection 392
10:11:12 INFO - 1499101872744 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49857
10:11:12 INFO - 1499101872745 Marionette DEBUG Closed connection 393
10:11:12 INFO - 1499101872746 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49858
10:11:12 INFO - 1499101872747 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:11:12 INFO - 1499101872748 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:12 INFO - 1499101872750 Marionette DEBUG Register listener.js for window 4294967297
10:11:12 INFO - 1499101872753 Marionette TRACE 394 <- [1,1,null,{"sessionId":"727eb486-1a97-4486-a4f3-aa452da8e115","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:12 INFO - 1499101872755 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"}]
10:11:12 INFO - 1499101872757 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:11:12 INFO - 1499101872759 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:11:12 INFO - 1499101872760 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:11:12 INFO - 1499101872767 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"
10:11:12 INFO - 1499101872771 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"
10:11:12 INFO - 1499101872778 Marionette TRACE 394 <- [1,2,null,{}]
10:11:12 INFO - 1499101872783 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:11:12 INFO - 1499101872790 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9edaeb73-508e-4033-9635-6ed0e5ecdd3d","ELEMENT":"9edaeb73-508e-4033-9635-6ed0e5ecdd3d"}}]
10:11:12 INFO - 1499101872791 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:11:12 INFO - 1499101872794 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8dc2e118-7ab3-44a4-b352-20accc51d01e","ELEMENT":"8dc2e118-7ab3-44a4-b352-20accc51d01e"}}]
10:11:12 INFO - 1499101872795 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9edaeb73-508e-4033-9635-6ed0e5ecdd3d"}]
10:11:13 INFO - 1499101873037 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:11:13 INFO - 1499101873038 Marionette TRACE 394 <- [1,5,null,{}]
10:11:13 INFO - 1499101873039 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"8dc2e118-7ab3-44a4-b352-20accc51d01e"}]
10:11:13 INFO - 1499101873057 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
10:11:13 INFO - 1499101873059 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:11:13 INFO - 1499101873066 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:11:13 INFO - 1499101873067 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}]
10:11:13 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499101873070 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:11:13 INFO - 1499101873072 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:11:13 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 371ms
10:11:13 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:11:13 INFO - 1499101873073 Marionette TRACE 394 <- [1,9,null,{}]
10:11:13 INFO - 1499101873075 Marionette DEBUG Closed connection 394
10:11:13 INFO - 1499101873076 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49859
10:11:13 INFO - 1499101873077 Marionette DEBUG Closed connection 395
10:11:13 INFO - 1499101873078 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49860
10:11:13 INFO - 1499101873079 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:13 INFO - 1499101873079 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:13 INFO - 1499101873083 Marionette DEBUG Register listener.js for window 4294967297
10:11:13 INFO - 1499101873086 Marionette TRACE 396 <- [1,1,null,{"sessionId":"f70678f5-81a3-4aa9-8835-e2fb7b382e13","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:13 INFO - 1499101873087 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:11:13 INFO - 1499101873088 Marionette TRACE 396 <- [1,2,null,{}]
10:11:13 INFO - 1499101873089 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:11:13 INFO - 1499101873089 Marionette TRACE 396 <- [1,3,null,{}]
10:11:13 INFO - 1499101873090 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:11:13 INFO - 1499101873091 Marionette TRACE 396 <- [1,4,null,{}]
10:11:13 INFO - 1499101873092 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}]
10:11:13 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499101873096 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:11:13 INFO - 1499101873097 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:11:13 INFO - 1499101873098 Marionette TRACE 396 <- [1,6,null,{}]
10:11:13 INFO - 1499101873100 Marionette DEBUG Closed connection 396
10:11:13 INFO - 1499101873101 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49861
10:11:13 INFO - 1499101873102 Marionette DEBUG Closed connection 397
10:11:13 INFO - 1499101873102 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49862
10:11:13 INFO - 1499101873104 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:11:13 INFO - 1499101873104 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:13 INFO - 1499101873107 Marionette DEBUG Register listener.js for window 4294967297
10:11:13 INFO - 1499101873110 Marionette TRACE 398 <- [1,1,null,{"sessionId":"86a4092a-356e-4620-8a47-d70fa65fd4b5","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:13 INFO - 1499101873111 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:11:13 INFO - 1499101873113 Marionette TRACE 398 <- [1,2,null,{}]
10:11:13 INFO - 1499101873114 Marionette DEBUG Closed connection 398
10:11:13 INFO - 1499101873115 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49863
10:11:13 INFO - 1499101873116 Marionette DEBUG Closed connection 399
10:11:13 INFO - 1499101873117 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49864
10:11:13 INFO - 1499101873118 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:11:13 INFO - 1499101873119 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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}
10:11:13 INFO - 1499101873123 Marionette DEBUG Register listener.js for window 4294967297
10:11:13 INFO - 1499101873126 Marionette TRACE 400 <- [1,1,null,{"sessionId":"eca6a87a-8e95-4d84-8780-589bdcaeb8e9","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":4044,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppqafyc.mozrunner","moz:accessibilityChecks":false}}]
10:11:13 INFO - 1499101873127 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"}]
10:11:13 INFO - 1499101873129 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"
10:11:13 INFO - 1499101873131 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"
10:11:13 INFO - 1499101873132 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"
10:11:13 INFO - 1499101873139 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"
10:11:13 INFO - 1499101873142 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"
10:11:13 INFO - 1499101873147 Marionette TRACE 400 <- [1,2,null,{}]
10:11:13 INFO - 1499101873159 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:11:13 INFO - 1499101873163 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"794a3291-9a65-4e59-9e5b-5a1b91a21102","ELEMENT":"794a3291-9a65-4e59-9e5b-5a1b91a21102"}}]
10:11:13 INFO - 1499101873164 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"794a3291-9a65-4e59-9e5b-5a1b91a21102"}]
10:11:13 INFO - 1499101873192 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:11:13 INFO - 1499101873193 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"794a3291-9a65-4e59-9e5b-5a1b91a21102"}]
10:11:13 INFO - 1499101873211 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element