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]
05:37:35 INFO - 1500899855961 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}]
05:37:35 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500899855964 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:37:35 INFO - 1500899855965 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:37:35 INFO - 1500899855967 Marionette TRACE 352 <- [1,6,null,{}]
05:37:35 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 155ms
05:37:35 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:37:35 INFO - 1500899855968 Marionette DEBUG Closed connection 352
05:37:35 INFO - 1500899855970 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49795
05:37:35 INFO - 1500899855970 Marionette DEBUG Closed connection 353
05:37:35 INFO - 1500899855971 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49796
05:37:35 INFO - 1500899855972 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:35 INFO - 1500899855973 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:35 INFO - 1500899855976 Marionette DEBUG Register listener.js for window 4294967297
05:37:35 INFO - 1500899855980 Marionette TRACE 354 <- [1,1,null,{"sessionId":"76d70477-d911-47f9-a3f2-f1b2d1415605","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:35 INFO - 1500899855981 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:37:35 INFO - 1500899855982 Marionette TRACE 354 <- [1,2,null,{}]
05:37:35 INFO - 1500899855983 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:37:35 INFO - 1500899855983 Marionette TRACE 354 <- [1,3,null,{}]
05:37:35 INFO - 1500899855984 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:37:35 INFO - 1500899855985 Marionette TRACE 354 <- [1,4,null,{}]
05:37:35 INFO - 1500899855987 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}]
05:37:35 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500899855990 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:37:35 INFO - 1500899855991 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:37:35 INFO - 1500899855992 Marionette TRACE 354 <- [1,6,null,{}]
05:37:35 INFO - 1500899855994 Marionette DEBUG Closed connection 354
05:37:35 INFO - 1500899855995 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49797
05:37:35 INFO - 1500899855996 Marionette DEBUG Closed connection 355
05:37:35 INFO - 1500899855996 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49798
05:37:35 INFO - 1500899855998 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:35 INFO - 1500899855998 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856001 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856005 Marionette TRACE 356 <- [1,1,null,{"sessionId":"6ee9c53c-f95c-47a0-8a6f-43a8c56a47c9","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856006 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:37:36 INFO - 1500899856008 Marionette TRACE 356 <- [1,2,null,{}]
05:37:36 INFO - 1500899856009 Marionette DEBUG Closed connection 356
05:37:36 INFO - 1500899856010 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49799
05:37:36 INFO - 1500899856011 Marionette DEBUG Closed connection 357
05:37:36 INFO - 1500899856012 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49800
05:37:36 INFO - 1500899856013 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:37:36 INFO - 1500899856014 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856016 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856020 Marionette TRACE 358 <- [1,1,null,{"sessionId":"25f72483-3cdc-4b6f-bb21-7e616449de9d","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856021 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49352/clicks.html"}]
05:37:36 INFO - 1500899856023 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49352/hidden.html"
05:37:36 INFO - 1500899856034 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49352/hidden.html"
05:37:36 INFO - 1500899856034 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49352/hidden.html"
05:37:36 INFO - 1500899856041 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49352/clicks.html"
05:37:36 INFO - 1500899856047 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49352/clicks.html"
05:37:36 INFO - 1500899856051 Marionette TRACE 358 <- [1,2,null,{}]
05:37:36 INFO - 1500899856056 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:37:36 INFO - 1500899856059 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"03f1d9a3-0bea-4627-ae9e-9a5d6629f9cf","ELEMENT":"03f1d9a3-0bea-4627-ae9e-9a5d6629f9cf"}}]
05:37:36 INFO - 1500899856060 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"03f1d9a3-0bea-4627-ae9e-9a5d6629f9cf"}]
05:37:36 INFO - 1500899856105 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49352/clicks.html"
05:37:36 INFO - 1500899856119 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49352/clicks.html"
05:37:36 INFO - 1500899856119 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49352/clicks.html"
05:37:36 INFO - 1500899856131 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49352/test.html"
05:37:36 INFO - 1500899856135 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49352/test.html"
05:37:36 INFO - 1500899856139 Marionette TRACE 358 <- [1,4,null,{}]
05:37:36 INFO - 1500899856143 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:37:36 INFO - 1500899856151 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"65b6d23b-7886-488f-a714-7aaa0e1c861c","ELEMENT":"65b6d23b-7886-488f-a714-7aaa0e1c861c"}}]
05:37:36 INFO - 1500899856152 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:37:36 INFO - 1500899856153 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:37:36 INFO - 1500899856155 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}]
05:37:36 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500899856159 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:37:36 INFO - 1500899856161 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:37:36 INFO - 1500899856162 Marionette TRACE 358 <- [1,8,null,{}]
05:37:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 196ms
05:37:36 INFO - TEST-START | test_click.py TestClick.test_container_element
05:37:36 INFO - 1500899856165 Marionette DEBUG Closed connection 358
05:37:36 INFO - 1500899856166 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49803
05:37:36 INFO - 1500899856167 Marionette DEBUG Closed connection 359
05:37:36 INFO - 1500899856167 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49804
05:37:36 INFO - 1500899856169 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:36 INFO - 1500899856169 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856172 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856176 Marionette TRACE 360 <- [1,1,null,{"sessionId":"0c85f6c0-e398-4ff0-85cb-74f3a7737580","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856177 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:37:36 INFO - 1500899856178 Marionette TRACE 360 <- [1,2,null,{}]
05:37:36 INFO - 1500899856179 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:37:36 INFO - 1500899856179 Marionette TRACE 360 <- [1,3,null,{}]
05:37:36 INFO - 1500899856180 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:37:36 INFO - 1500899856181 Marionette TRACE 360 <- [1,4,null,{}]
05:37:36 INFO - 1500899856183 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}]
05:37:36 INFO - TEST-START: test_click.py TestClick.test_container_element1500899856186 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:37:36 INFO - 1500899856187 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:37:36 INFO - 1500899856188 Marionette TRACE 360 <- [1,6,null,{}]
05:37:36 INFO - 1500899856190 Marionette DEBUG Closed connection 360
05:37:36 INFO - 1500899856191 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49805
05:37:36 INFO - 1500899856192 Marionette DEBUG Closed connection 361
05:37:36 INFO - 1500899856193 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49806
05:37:36 INFO - 1500899856194 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:36 INFO - 1500899856194 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856197 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856201 Marionette TRACE 362 <- [1,1,null,{"sessionId":"7ab8d246-0541-44f7-bb8c-3b0b9f00dd4d","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856202 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:37:36 INFO - 1500899856203 Marionette TRACE 362 <- [1,2,null,{}]
05:37:36 INFO - 1500899856205 Marionette DEBUG Closed connection 362
05:37:36 INFO - 1500899856206 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49807
05:37:36 INFO - 1500899856207 Marionette DEBUG Closed connection 363
05:37:36 INFO - 1500899856207 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49808
05:37:36 INFO - 1500899856208 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:37:36 INFO - 1500899856209 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856212 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856215 Marionette TRACE 364 <- [1,1,null,{"sessionId":"e1ef88d1-def4-4bdc-b970-8071e6691384","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856217 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"}]
05:37:36 INFO - 1500899856219 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49352/test.html"
05:37:36 INFO - 1500899856221 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49352/test.html"
05:37:36 INFO - 1500899856221 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49352/test.html"
05:37:36 INFO - 1500899856234 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"
05:37:36 INFO - 1500899856235 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"
05:37:36 INFO - 1500899856247 Marionette TRACE 364 <- [1,2,null,{}]
05:37:36 INFO - 1500899856257 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:37:36 INFO - 1500899856260 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f9a49db1-289b-437c-a935-d617b48a89a4","ELEMENT":"f9a49db1-289b-437c-a935-d617b48a89a4"}}]
05:37:36 INFO - 1500899856261 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"f9a49db1-289b-437c-a935-d617b48a89a4"}]
05:37:36 INFO - 1500899856505 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:37:36 INFO - 1500899856506 Marionette TRACE 364 <- [1,4,null,{}]
05:37:36 INFO - 1500899856507 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"f9a49db1-289b-437c-a935-d617b48a89a4","name":"selected"}]
05:37:36 INFO - 1500899856512 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:37:36 INFO - 1500899856514 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}]
05:37:36 INFO - TEST-END: test_click.py TestClick.test_container_element1500899856517 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:37:36 INFO - 1500899856518 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:37:36 INFO - 1500899856520 Marionette TRACE 364 <- [1,7,null,{}]
05:37:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 357ms
05:37:36 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:37:36 INFO - 1500899856522 Marionette DEBUG Closed connection 364
05:37:36 INFO - 1500899856522 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49809
05:37:36 INFO - 1500899856523 Marionette DEBUG Closed connection 365
05:37:36 INFO - 1500899856524 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49810
05:37:36 INFO - 1500899856525 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:36 INFO - 1500899856526 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856529 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856532 Marionette TRACE 366 <- [1,1,null,{"sessionId":"55220bf0-77ba-4db0-8428-9b1e5f67ab57","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856533 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:37:36 INFO - 1500899856534 Marionette TRACE 366 <- [1,2,null,{}]
05:37:36 INFO - 1500899856535 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:37:36 INFO - 1500899856535 Marionette TRACE 366 <- [1,3,null,{}]
05:37:36 INFO - 1500899856536 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:37:36 INFO - 1500899856537 Marionette TRACE 366 <- [1,4,null,{}]
05:37:36 INFO - 1500899856539 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}]
05:37:36 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500899856542 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:37:36 INFO - 1500899856543 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:37:36 INFO - 1500899856544 Marionette TRACE 366 <- [1,6,null,{}]
05:37:36 INFO - 1500899856546 Marionette DEBUG Closed connection 366
05:37:36 INFO - 1500899856547 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49811
05:37:36 INFO - 1500899856548 Marionette DEBUG Closed connection 367
05:37:36 INFO - 1500899856548 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49812
05:37:36 INFO - 1500899856550 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:36 INFO - 1500899856550 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856553 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856565 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ab4056a8-d003-44f2-83ce-059886ebad41","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856566 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:37:36 INFO - 1500899856567 Marionette TRACE 368 <- [1,2,null,{}]
05:37:36 INFO - 1500899856569 Marionette DEBUG Closed connection 368
05:37:36 INFO - 1500899856570 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49813
05:37:36 INFO - 1500899856571 Marionette DEBUG Closed connection 369
05:37:36 INFO - 1500899856572 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49814
05:37:36 INFO - 1500899856573 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:37:36 INFO - 1500899856573 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856576 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856580 Marionette TRACE 370 <- [1,1,null,{"sessionId":"5b1440e4-178f-4513-9dcc-7d16f42cdf58","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856581 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"}]
05:37:36 INFO - 1500899856583 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"
05:37:36 INFO - 1500899856585 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"
05:37:36 INFO - 1500899856586 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"
05:37:36 INFO - 1500899856593 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"
05:37:36 INFO - 1500899856596 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"
05:37:36 INFO - 1500899856605 Marionette TRACE 370 <- [1,2,null,{}]
05:37:36 INFO - 1500899856609 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:37:36 INFO - 1500899856617 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eb427340-3ccd-4d27-97ce-5ca4aa7adcd1","ELEMENT":"eb427340-3ccd-4d27-97ce-5ca4aa7adcd1"}}]
05:37:36 INFO - 1500899856618 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"eb427340-3ccd-4d27-97ce-5ca4aa7adcd1"}]
05:37:36 INFO - 1500899856869 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:37:36 INFO - 1500899856870 Marionette TRACE 370 <- [1,4,null,{}]
05:37:36 INFO - 1500899856871 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"eb427340-3ccd-4d27-97ce-5ca4aa7adcd1","name":"selected"}]
05:37:36 INFO - 1500899856876 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:37:36 INFO - 1500899856878 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}]
05:37:36 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500899856881 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:37:36 INFO - 1500899856882 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:37:36 INFO - 1500899856884 Marionette TRACE 370 <- [1,7,null,{}]
05:37:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 364ms
05:37:36 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:37:36 INFO - 1500899856885 Marionette DEBUG Closed connection 370
05:37:36 INFO - 1500899856886 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49815
05:37:36 INFO - 1500899856887 Marionette DEBUG Closed connection 371
05:37:36 INFO - 1500899856888 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49816
05:37:36 INFO - 1500899856889 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:36 INFO - 1500899856889 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856892 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856896 Marionette TRACE 372 <- [1,1,null,{"sessionId":"942cf754-ae6c-4573-95f3-8c599e7998f7","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856897 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:37:36 INFO - 1500899856898 Marionette TRACE 372 <- [1,2,null,{}]
05:37:36 INFO - 1500899856899 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:37:36 INFO - 1500899856899 Marionette TRACE 372 <- [1,3,null,{}]
05:37:36 INFO - 1500899856900 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:37:36 INFO - 1500899856901 Marionette TRACE 372 <- [1,4,null,{}]
05:37:36 INFO - 1500899856902 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}]
05:37:36 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500899856905 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:37:36 INFO - 1500899856906 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:37:36 INFO - 1500899856908 Marionette TRACE 372 <- [1,6,null,{}]
05:37:36 INFO - 1500899856910 Marionette DEBUG Closed connection 372
05:37:36 INFO - 1500899856910 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49817
05:37:36 INFO - 1500899856911 Marionette DEBUG Closed connection 373
05:37:36 INFO - 1500899856912 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49818
05:37:36 INFO - 1500899856913 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:36 INFO - 1500899856914 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856916 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856920 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ab65b541-613f-4351-bd3a-15def3155dac","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856921 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:37:36 INFO - 1500899856922 Marionette TRACE 374 <- [1,2,null,{}]
05:37:36 INFO - 1500899856924 Marionette DEBUG Closed connection 374
05:37:36 INFO - 1500899856925 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49819
05:37:36 INFO - 1500899856926 Marionette DEBUG Closed connection 375
05:37:36 INFO - 1500899856926 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49820
05:37:36 INFO - 1500899856929 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:37:36 INFO - 1500899856930 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:36 INFO - 1500899856932 Marionette DEBUG Register listener.js for window 4294967297
05:37:36 INFO - 1500899856936 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e3156dc1-4e93-4cb5-adf3-b929d4ead0c5","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:36 INFO - 1500899856937 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"}]
05:37:36 INFO - 1500899856939 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"
05:37:36 INFO - 1500899856942 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"
05:37:36 INFO - 1500899856942 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"
05:37:36 INFO - 1500899856949 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"
05:37:36 INFO - 1500899856952 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"
05:37:36 INFO - 1500899856958 Marionette TRACE 376 <- [1,2,null,{}]
05:37:36 INFO - 1500899856971 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:37:36 INFO - 1500899856974 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d13a7a28-0959-4687-a4a2-bce5f90df8c1","ELEMENT":"d13a7a28-0959-4687-a4a2-bce5f90df8c1"}}]
05:37:36 INFO - 1500899856975 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d13a7a28-0959-4687-a4a2-bce5f90df8c1"}]
05:37:37 INFO - 1500899857216 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:37:37 INFO - 1500899857217 Marionette TRACE 376 <- [1,4,null,{}]
05:37:37 INFO - 1500899857219 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}]
05:37:37 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500899857222 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:37:37 INFO - 1500899857223 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:37:37 INFO - 1500899857225 Marionette TRACE 376 <- [1,6,null,{}]
05:37:37 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 341ms
05:37:37 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:37:37 INFO - 1500899857227 Marionette DEBUG Closed connection 376
05:37:37 INFO - 1500899857227 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49821
05:37:37 INFO - 1500899857228 Marionette DEBUG Closed connection 377
05:37:37 INFO - 1500899857229 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49822
05:37:37 INFO - 1500899857230 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:37 INFO - 1500899857231 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857234 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857237 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f93f25a1-2cc5-4fdd-993f-8a8cf4801825","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857239 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:37:37 INFO - 1500899857239 Marionette TRACE 378 <- [1,2,null,{}]
05:37:37 INFO - 1500899857240 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:37:37 INFO - 1500899857241 Marionette TRACE 378 <- [1,3,null,{}]
05:37:37 INFO - 1500899857242 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:37:37 INFO - 1500899857242 Marionette TRACE 378 <- [1,4,null,{}]
05:37:37 INFO - 1500899857244 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}]
05:37:37 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500899857247 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:37:37 INFO - 1500899857248 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:37:37 INFO - 1500899857250 Marionette TRACE 378 <- [1,6,null,{}]
05:37:37 INFO - 1500899857251 Marionette DEBUG Closed connection 378
05:37:37 INFO - 1500899857252 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49823
05:37:37 INFO - 1500899857253 Marionette DEBUG Closed connection 379
05:37:37 INFO - 1500899857254 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49824
05:37:37 INFO - 1500899857255 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:37 INFO - 1500899857256 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857258 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857262 Marionette TRACE 380 <- [1,1,null,{"sessionId":"5851c3e9-243c-4bbc-aeba-4ba7aa4d4ec5","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857263 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:37:37 INFO - 1500899857265 Marionette TRACE 380 <- [1,2,null,{}]
05:37:37 INFO - 1500899857266 Marionette DEBUG Closed connection 380
05:37:37 INFO - 1500899857267 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49825
05:37:37 INFO - 1500899857268 Marionette DEBUG Closed connection 381
05:37:37 INFO - 1500899857268 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49826
05:37:37 INFO - 1500899857270 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:37:37 INFO - 1500899857270 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857273 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857277 Marionette TRACE 382 <- [1,1,null,{"sessionId":"fcccabff-ecae-4ef3-8e22-9a1568807f63","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857278 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"}]
05:37:37 INFO - 1500899857280 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"
05:37:37 INFO - 1500899857282 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"
05:37:37 INFO - 1500899857282 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"
05:37:37 INFO - 1500899857289 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"
05:37:37 INFO - 1500899857293 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"
05:37:37 INFO - 1500899857296 Marionette TRACE 382 <- [1,2,null,{}]
05:37:37 INFO - 1500899857306 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:37:37 INFO - 1500899857310 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f4f689ae-5840-4642-9773-fd948c9565b9","ELEMENT":"f4f689ae-5840-4642-9773-fd948c9565b9"}}]
05:37:37 INFO - 1500899857311 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f4f689ae-5840-4642-9773-fd948c9565b9"}]
05:37:37 INFO - 1500899857555 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:37:37 INFO - 1500899857556 Marionette TRACE 382 <- [1,4,null,{}]
05:37:37 INFO - 1500899857558 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}]
05:37:37 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500899857561 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:37:37 INFO - 1500899857562 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:37:37 INFO - 1500899857564 Marionette TRACE 382 <- [1,6,null,{}]
05:37:37 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 339ms
05:37:37 INFO - TEST-START | test_click.py TestClick.test_input_file
05:37:37 INFO - 1500899857565 Marionette DEBUG Closed connection 382
05:37:37 INFO - 1500899857566 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49827
05:37:37 INFO - 1500899857567 Marionette DEBUG Closed connection 383
05:37:37 INFO - 1500899857568 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49828
05:37:37 INFO - 1500899857569 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:37 INFO - 1500899857570 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857572 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857576 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4032dbe0-2c9b-4ab4-9ecf-548ec17df496","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857577 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:37:37 INFO - 1500899857578 Marionette TRACE 384 <- [1,2,null,{}]
05:37:37 INFO - 1500899857579 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:37:37 INFO - 1500899857579 Marionette TRACE 384 <- [1,3,null,{}]
05:37:37 INFO - 1500899857580 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:37:37 INFO - 1500899857581 Marionette TRACE 384 <- [1,4,null,{}]
05:37:37 INFO - 1500899857583 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}]
05:37:37 INFO - TEST-START: test_click.py TestClick.test_input_file1500899857585 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:37:37 INFO - 1500899857586 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:37:37 INFO - 1500899857588 Marionette TRACE 384 <- [1,6,null,{}]
05:37:37 INFO - 1500899857590 Marionette DEBUG Closed connection 384
05:37:37 INFO - 1500899857590 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49829
05:37:37 INFO - 1500899857591 Marionette DEBUG Closed connection 385
05:37:37 INFO - 1500899857592 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49830
05:37:37 INFO - 1500899857593 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:37 INFO - 1500899857594 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857596 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857600 Marionette TRACE 386 <- [1,1,null,{"sessionId":"00b59ee6-4bea-41b4-aecb-89f60b26d7ce","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857601 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:37:37 INFO - 1500899857602 Marionette TRACE 386 <- [1,2,null,{}]
05:37:37 INFO - 1500899857604 Marionette DEBUG Closed connection 386
05:37:37 INFO - 1500899857605 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49831
05:37:37 INFO - 1500899857606 Marionette DEBUG Closed connection 387
05:37:37 INFO - 1500899857606 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49832
05:37:37 INFO - 1500899857607 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:37:37 INFO - 1500899857608 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857611 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857614 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2e47a5c6-02da-47bf-9720-23e5296c573a","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857615 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:37:37 INFO - 1500899857617 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"
05:37:37 INFO - 1500899857619 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"
05:37:37 INFO - 1500899857620 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"
05:37:37 INFO - 1500899857627 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:37:37 INFO - 1500899857633 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:37:37 INFO - 1500899857640 Marionette TRACE 388 <- [1,2,null,{}]
05:37:37 INFO - 1500899857644 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:37:37 INFO - 1500899857652 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bbf941cd-769e-46cc-8cd3-28efefdcb7c5","ELEMENT":"bbf941cd-769e-46cc-8cd3-28efefdcb7c5"}}]
05:37:37 INFO - 1500899857653 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"bbf941cd-769e-46cc-8cd3-28efefdcb7c5"}]
05:37:37 INFO - 1500899857693 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]
05:37:37 INFO - 1500899857695 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}]
05:37:37 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 137ms
05:37:37 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:37:37 INFO - TEST-END: test_click.py TestClick.test_input_file1500899857698 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:37:37 INFO - 1500899857699 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:37:37 INFO - 1500899857701 Marionette TRACE 388 <- [1,6,null,{}]
05:37:37 INFO - 1500899857702 Marionette DEBUG Closed connection 388
05:37:37 INFO - 1500899857703 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49833
05:37:37 INFO - 1500899857704 Marionette DEBUG Closed connection 389
05:37:37 INFO - 1500899857705 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49834
05:37:37 INFO - 1500899857706 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:37 INFO - 1500899857706 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857709 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857713 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c28af742-bd52-4551-ba49-00dbe3fddaf5","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857714 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:37:37 INFO - 1500899857714 Marionette TRACE 390 <- [1,2,null,{}]
05:37:37 INFO - 1500899857715 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:37:37 INFO - 1500899857716 Marionette TRACE 390 <- [1,3,null,{}]
05:37:37 INFO - 1500899857717 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:37:37 INFO - 1500899857717 Marionette TRACE 390 <- [1,4,null,{}]
05:37:37 INFO - 1500899857719 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}]
05:37:37 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500899857722 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:37:37 INFO - 1500899857723 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:37:37 INFO - 1500899857725 Marionette TRACE 390 <- [1,6,null,{}]
05:37:37 INFO - 1500899857727 Marionette DEBUG Closed connection 390
05:37:37 INFO - 1500899857728 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49835
05:37:37 INFO - 1500899857729 Marionette DEBUG Closed connection 391
05:37:37 INFO - 1500899857729 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49836
05:37:37 INFO - 1500899857730 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:37 INFO - 1500899857731 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857734 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857737 Marionette TRACE 392 <- [1,1,null,{"sessionId":"7c0f2b5d-9e8f-4aad-aa9d-fe4883781cdd","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857739 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:37:37 INFO - 1500899857740 Marionette TRACE 392 <- [1,2,null,{}]
05:37:37 INFO - 1500899857742 Marionette DEBUG Closed connection 392
05:37:37 INFO - 1500899857742 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49837
05:37:37 INFO - 1500899857743 Marionette DEBUG Closed connection 393
05:37:37 INFO - 1500899857744 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49838
05:37:37 INFO - 1500899857746 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:37:37 INFO - 1500899857746 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:37 INFO - 1500899857763 Marionette DEBUG Register listener.js for window 4294967297
05:37:37 INFO - 1500899857767 Marionette TRACE 394 <- [1,1,null,{"sessionId":"ebc7f1e8-adb2-4932-8267-5f28b7bc24be","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:37 INFO - 1500899857768 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"}]
05:37:37 INFO - 1500899857817 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:37:37 INFO - 1500899857820 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:37:37 INFO - 1500899857820 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:37:37 INFO - 1500899857827 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"
05:37:37 INFO - 1500899857830 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"
05:37:37 INFO - 1500899857840 Marionette TRACE 394 <- [1,2,null,{}]
05:37:37 INFO - 1500899857845 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:37:37 INFO - 1500899857858 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dac2f5bd-4b3b-48bd-b079-481c56813ce0","ELEMENT":"dac2f5bd-4b3b-48bd-b079-481c56813ce0"}}]
05:37:37 INFO - 1500899857859 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:37:37 INFO - 1500899857862 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88bd92d9-2bdc-4c95-ae7a-76727f6c16bf","ELEMENT":"88bd92d9-2bdc-4c95-ae7a-76727f6c16bf"}}]
05:37:37 INFO - 1500899857863 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"dac2f5bd-4b3b-48bd-b079-481c56813ce0"}]
05:37:38 INFO - 1500899858103 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:37:38 INFO - 1500899858104 Marionette TRACE 394 <- [1,5,null,{}]
05:37:38 INFO - 1500899858105 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"88bd92d9-2bdc-4c95-ae7a-76727f6c16bf"}]
05:37:38 INFO - 1500899858123 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]
05:37:38 INFO - 1500899858125 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:37:38 INFO - 1500899858129 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:37:38 INFO - 1500899858130 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}]
05:37:38 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500899858133 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:37:38 INFO - 1500899858134 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:37:38 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 435ms
05:37:38 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:37:38 INFO - 1500899858136 Marionette TRACE 394 <- [1,9,null,{}]
05:37:38 INFO - 1500899858138 Marionette DEBUG Closed connection 394
05:37:38 INFO - 1500899858138 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49839
05:37:38 INFO - 1500899858139 Marionette DEBUG Closed connection 395
05:37:38 INFO - 1500899858140 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49840
05:37:38 INFO - 1500899858141 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:38 INFO - 1500899858142 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:38 INFO - 1500899858145 Marionette DEBUG Register listener.js for window 4294967297
05:37:38 INFO - 1500899858148 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ea977ff8-034e-45f9-9763-571246046b48","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:38 INFO - 1500899858149 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:37:38 INFO - 1500899858150 Marionette TRACE 396 <- [1,2,null,{}]
05:37:38 INFO - 1500899858151 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:37:38 INFO - 1500899858151 Marionette TRACE 396 <- [1,3,null,{}]
05:37:38 INFO - 1500899858152 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:37:38 INFO - 1500899858153 Marionette TRACE 396 <- [1,4,null,{}]
05:37:38 INFO - 1500899858154 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}]
05:37:38 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500899858157 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:37:38 INFO - 1500899858158 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:37:38 INFO - 1500899858160 Marionette TRACE 396 <- [1,6,null,{}]
05:37:38 INFO - 1500899858161 Marionette DEBUG Closed connection 396
05:37:38 INFO - 1500899858162 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49841
05:37:38 INFO - 1500899858164 Marionette DEBUG Closed connection 397
05:37:38 INFO - 1500899858165 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49842
05:37:38 INFO - 1500899858166 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:37:38 INFO - 1500899858167 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:38 INFO - 1500899858170 Marionette DEBUG Register listener.js for window 4294967297
05:37:38 INFO - 1500899858173 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4bfc360a-526e-4133-ac7e-0e48f0bc71b3","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:38 INFO - 1500899858174 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:37:38 INFO - 1500899858176 Marionette TRACE 398 <- [1,2,null,{}]
05:37:38 INFO - 1500899858177 Marionette DEBUG Closed connection 398
05:37:38 INFO - 1500899858178 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49843
05:37:38 INFO - 1500899858179 Marionette DEBUG Closed connection 399
05:37:38 INFO - 1500899858180 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49844
05:37:38 INFO - 1500899858181 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:37:38 INFO - 1500899858182 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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}
05:37:38 INFO - 1500899858185 Marionette DEBUG Register listener.js for window 4294967297
05:37:38 INFO - 1500899858188 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e35485bb-d3c9-4198-b97a-a1ed1bad700b","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":2304,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprj8qzt.mozrunner","moz:accessibilityChecks":false}}]
05:37:38 INFO - 1500899858189 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"}]
05:37:38 INFO - 1500899858191 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"
05:37:38 INFO - 1500899858193 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"
05:37:38 INFO - 1500899858194 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"
05:37:38 INFO - 1500899858201 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"
05:37:38 INFO - 1500899858205 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"
05:37:38 INFO - 1500899858211 Marionette TRACE 400 <- [1,2,null,{}]
05:37:38 INFO - 1500899858222 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:37:38 INFO - 1500899858225 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f580d8f2-acb7-4d5d-bc1d-1ee868db53be","ELEMENT":"f580d8f2-acb7-4d5d-bc1d-1ee868db53be"}}]
05:37:38 INFO - 1500899858226 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f580d8f2-acb7-4d5d-bc1d-1ee868db53be"}]
05:37:38 INFO - 1500899858252 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:37:38 INFO - 1500899858253 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f580d8f2-acb7-4d5d-bc1d-1ee868db53be"}]
05:37:38 INFO - 1500899858271 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element