could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:46:06 INFO - 1500399966139 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:46:06 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500399966142 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:46:06 INFO - 1500399966143 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:46:06 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 151ms
10:46:06 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:46:06 INFO - 1500399966145 Marionette TRACE 352 <- [1,6,null,{}]
10:46:06 INFO - 1500399966146 Marionette DEBUG Closed connection 352
10:46:06 INFO - 1500399966147 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49905
10:46:06 INFO - 1500399966148 Marionette DEBUG Closed connection 353
10:46:06 INFO - 1500399966149 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49906
10:46:06 INFO - 1500399966150 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:06 INFO - 1500399966151 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966154 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966158 Marionette TRACE 354 <- [1,1,null,{"sessionId":"dc377190-8b35-4791-b575-a7ca3b16098d","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966159 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:46:06 INFO - 1500399966160 Marionette TRACE 354 <- [1,2,null,{}]
10:46:06 INFO - 1500399966161 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:46:06 INFO - 1500399966161 Marionette TRACE 354 <- [1,3,null,{}]
10:46:06 INFO - 1500399966162 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:46:06 INFO - 1500399966163 Marionette TRACE 354 <- [1,4,null,{}]
10:46:06 INFO - 1500399966165 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:46:06 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500399966168 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:46:06 INFO - 1500399966169 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:46:06 INFO - 1500399966171 Marionette TRACE 354 <- [1,6,null,{}]
10:46:06 INFO - 1500399966172 Marionette DEBUG Closed connection 354
10:46:06 INFO - 1500399966173 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49907
10:46:06 INFO - 1500399966174 Marionette DEBUG Closed connection 355
10:46:06 INFO - 1500399966175 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49908
10:46:06 INFO - 1500399966176 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:06 INFO - 1500399966177 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966179 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966183 Marionette TRACE 356 <- [1,1,null,{"sessionId":"bb80ac2f-447d-473f-84da-2692aa426c37","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966184 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:46:06 INFO - 1500399966186 Marionette TRACE 356 <- [1,2,null,{}]
10:46:06 INFO - 1500399966187 Marionette DEBUG Closed connection 356
10:46:06 INFO - 1500399966188 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49909
10:46:06 INFO - 1500399966189 Marionette DEBUG Closed connection 357
10:46:06 INFO - 1500399966190 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49910
10:46:06 INFO - 1500399966191 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:46:06 INFO - 1500399966192 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966194 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966198 Marionette TRACE 358 <- [1,1,null,{"sessionId":"1021cfa6-72de-4b88-b0ee-86a12c9ce148","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966199 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49465/clicks.html"}]
10:46:06 INFO - 1500399966202 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49465/hidden.html"
10:46:06 INFO - 1500399966214 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49465/hidden.html"
10:46:06 INFO - 1500399966214 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49465/hidden.html"
10:46:06 INFO - 1500399966226 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49465/clicks.html"
10:46:06 INFO - 1500399966228 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49465/clicks.html"
10:46:06 INFO - 1500399966231 Marionette TRACE 358 <- [1,2,null,{}]
10:46:06 INFO - 1500399966235 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:46:06 INFO - 1500399966238 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"418df3a4-369a-4ee7-8e5f-8e8fb7809037","ELEMENT":"418df3a4-369a-4ee7-8e5f-8e8fb7809037"}}]
10:46:06 INFO - 1500399966244 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"418df3a4-369a-4ee7-8e5f-8e8fb7809037"}]
10:46:06 INFO - 1500399966287 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49465/clicks.html"
10:46:06 INFO - 1500399966297 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49465/clicks.html"
10:46:06 INFO - 1500399966298 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49465/clicks.html"
10:46:06 INFO - 1500399966314 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49465/test.html"
10:46:06 INFO - 1500399966317 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49465/test.html"
10:46:06 INFO - 1500399966326 Marionette TRACE 358 <- [1,4,null,{}]
10:46:06 INFO - 1500399966335 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:46:06 INFO - 1500399966344 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da2c4195-d084-4ad1-af44-3548a66ce693","ELEMENT":"da2c4195-d084-4ad1-af44-3548a66ce693"}}]
10:46:06 INFO - 1500399966346 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:46:06 INFO - 1500399966346 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:46:06 INFO - 1500399966348 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:46:06 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500399966352 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:46:06 INFO - 1500399966353 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:46:06 INFO - 1500399966355 Marionette TRACE 358 <- [1,8,null,{}]
10:46:06 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 210ms
10:46:06 INFO - TEST-START | test_click.py TestClick.test_container_element
10:46:06 INFO - 1500399966357 Marionette DEBUG Closed connection 358
10:46:06 INFO - 1500399966358 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49913
10:46:06 INFO - 1500399966359 Marionette DEBUG Closed connection 359
10:46:06 INFO - 1500399966360 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49914
10:46:06 INFO - 1500399966361 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:06 INFO - 1500399966361 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966364 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966368 Marionette TRACE 360 <- [1,1,null,{"sessionId":"bcd9f897-51fe-4f28-b249-5be186cb36e1","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966369 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:46:06 INFO - 1500399966370 Marionette TRACE 360 <- [1,2,null,{}]
10:46:06 INFO - 1500399966371 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:46:06 INFO - 1500399966371 Marionette TRACE 360 <- [1,3,null,{}]
10:46:06 INFO - 1500399966372 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:46:06 INFO - 1500399966373 Marionette TRACE 360 <- [1,4,null,{}]
10:46:06 INFO - 1500399966375 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:46:06 INFO - TEST-START: test_click.py TestClick.test_container_element1500399966378 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:46:06 INFO - 1500399966379 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:46:06 INFO - 1500399966381 Marionette TRACE 360 <- [1,6,null,{}]
10:46:06 INFO - 1500399966382 Marionette DEBUG Closed connection 360
10:46:06 INFO - 1500399966383 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49915
10:46:06 INFO - 1500399966384 Marionette DEBUG Closed connection 361
10:46:06 INFO - 1500399966385 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49916
10:46:06 INFO - 1500399966386 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:06 INFO - 1500399966386 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966389 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966393 Marionette TRACE 362 <- [1,1,null,{"sessionId":"effa1d44-9890-4817-a445-972f02b3c9b3","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966394 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:46:06 INFO - 1500399966396 Marionette TRACE 362 <- [1,2,null,{}]
10:46:06 INFO - 1500399966397 Marionette DEBUG Closed connection 362
10:46:06 INFO - 1500399966398 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49917
10:46:06 INFO - 1500399966399 Marionette DEBUG Closed connection 363
10:46:06 INFO - 1500399966400 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49918
10:46:06 INFO - 1500399966401 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:46:06 INFO - 1500399966402 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966405 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966408 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9a50eeb3-7a15-4132-9d0d-042e16ab404f","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966409 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:46:06 INFO - 1500399966411 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49465/test.html"
10:46:06 INFO - 1500399966414 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49465/test.html"
10:46:06 INFO - 1500399966414 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49465/test.html"
10:46:06 INFO - 1500399966421 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:46:06 INFO - 1500399966425 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:46:06 INFO - 1500399966433 Marionette TRACE 364 <- [1,2,null,{}]
10:46:06 INFO - 1500399966438 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:46:06 INFO - 1500399966445 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b261c422-7725-4a19-b733-808edf2eb030","ELEMENT":"b261c422-7725-4a19-b733-808edf2eb030"}}]
10:46:06 INFO - 1500399966446 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b261c422-7725-4a19-b733-808edf2eb030"}]
10:46:06 INFO - 1500399966693 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:46:06 INFO - 1500399966694 Marionette TRACE 364 <- [1,4,null,{}]
10:46:06 INFO - 1500399966695 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b261c422-7725-4a19-b733-808edf2eb030","name":"selected"}]
10:46:06 INFO - 1500399966700 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:46:06 INFO - 1500399966702 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:46:06 INFO - TEST-END: test_click.py TestClick.test_container_element1500399966706 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:46:06 INFO - 1500399966708 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:46:06 INFO - 1500399966709 Marionette TRACE 364 <- [1,7,null,{}]
10:46:06 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 354ms
10:46:06 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:46:06 INFO - 1500399966711 Marionette DEBUG Closed connection 364
10:46:06 INFO - 1500399966712 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49919
10:46:06 INFO - 1500399966713 Marionette DEBUG Closed connection 365
10:46:06 INFO - 1500399966714 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49920
10:46:06 INFO - 1500399966715 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:06 INFO - 1500399966715 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966718 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966722 Marionette TRACE 366 <- [1,1,null,{"sessionId":"a94693a8-ea1b-4936-a27f-4fbc04603a9d","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966723 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:46:06 INFO - 1500399966724 Marionette TRACE 366 <- [1,2,null,{}]
10:46:06 INFO - 1500399966725 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:46:06 INFO - 1500399966726 Marionette TRACE 366 <- [1,3,null,{}]
10:46:06 INFO - 1500399966726 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:46:06 INFO - 1500399966727 Marionette TRACE 366 <- [1,4,null,{}]
10:46:06 INFO - 1500399966729 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:46:06 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500399966732 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:46:06 INFO - 1500399966733 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:46:06 INFO - 1500399966734 Marionette TRACE 366 <- [1,6,null,{}]
10:46:06 INFO - 1500399966736 Marionette DEBUG Closed connection 366
10:46:06 INFO - 1500399966737 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49921
10:46:06 INFO - 1500399966738 Marionette DEBUG Closed connection 367
10:46:06 INFO - 1500399966738 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49922
10:46:06 INFO - 1500399966740 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:06 INFO - 1500399966740 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966743 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966746 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cd08f11a-d4fd-4245-9d02-e49abeb18303","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966748 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:46:06 INFO - 1500399966749 Marionette TRACE 368 <- [1,2,null,{}]
10:46:06 INFO - 1500399966751 Marionette DEBUG Closed connection 368
10:46:06 INFO - 1500399966752 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49923
10:46:06 INFO - 1500399966753 Marionette DEBUG Closed connection 369
10:46:06 INFO - 1500399966753 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49924
10:46:06 INFO - 1500399966755 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:46:06 INFO - 1500399966755 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:06 INFO - 1500399966758 Marionette DEBUG Register listener.js for window 2147483652
10:46:06 INFO - 1500399966762 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7f950792-05b2-46ca-9d21-dac1b5acaba2","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:06 INFO - 1500399966763 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:46:06 INFO - 1500399966765 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:46:06 INFO - 1500399966767 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:46:06 INFO - 1500399966768 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:46:06 INFO - 1500399966776 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:46:06 INFO - 1500399966778 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:46:06 INFO - 1500399966793 Marionette TRACE 370 <- [1,2,null,{}]
10:46:06 INFO - 1500399966799 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:46:06 INFO - 1500399966803 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cbb98e89-9c02-4948-ada9-c74a0ea8e74d","ELEMENT":"cbb98e89-9c02-4948-ada9-c74a0ea8e74d"}}]
10:46:06 INFO - 1500399966804 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"cbb98e89-9c02-4948-ada9-c74a0ea8e74d"}]
10:46:07 INFO - 1500399967050 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:46:07 INFO - 1500399967051 Marionette TRACE 370 <- [1,4,null,{}]
10:46:07 INFO - 1500399967052 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"cbb98e89-9c02-4948-ada9-c74a0ea8e74d","name":"selected"}]
10:46:07 INFO - 1500399967064 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:46:07 INFO - 1500399967065 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:46:07 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500399967068 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:46:07 INFO - 1500399967069 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:46:07 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 362ms
10:46:07 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:46:07 INFO - 1500399967071 Marionette TRACE 370 <- [1,7,null,{}]
10:46:07 INFO - 1500399967073 Marionette DEBUG Closed connection 370
10:46:07 INFO - 1500399967074 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49925
10:46:07 INFO - 1500399967074 Marionette DEBUG Closed connection 371
10:46:07 INFO - 1500399967075 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49926
10:46:07 INFO - 1500399967076 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:07 INFO - 1500399967077 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967086 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967097 Marionette TRACE 372 <- [1,1,null,{"sessionId":"0f82492b-262a-4f57-b1ba-39fa8c0d1175","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967099 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:46:07 INFO - 1500399967099 Marionette TRACE 372 <- [1,2,null,{}]
10:46:07 INFO - 1500399967100 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:46:07 INFO - 1500399967101 Marionette TRACE 372 <- [1,3,null,{}]
10:46:07 INFO - 1500399967102 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:46:07 INFO - 1500399967102 Marionette TRACE 372 <- [1,4,null,{}]
10:46:07 INFO - 1500399967104 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:46:07 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500399967107 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:46:07 INFO - 1500399967108 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:46:07 INFO - 1500399967110 Marionette TRACE 372 <- [1,6,null,{}]
10:46:07 INFO - 1500399967112 Marionette DEBUG Closed connection 372
10:46:07 INFO - 1500399967113 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49927
10:46:07 INFO - 1500399967114 Marionette DEBUG Closed connection 373
10:46:07 INFO - 1500399967114 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49928
10:46:07 INFO - 1500399967116 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:07 INFO - 1500399967116 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967119 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967123 Marionette TRACE 374 <- [1,1,null,{"sessionId":"1ac53f88-2ca5-4ddc-9b3e-a1cdcbc4b091","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967124 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:46:07 INFO - 1500399967125 Marionette TRACE 374 <- [1,2,null,{}]
10:46:07 INFO - 1500399967127 Marionette DEBUG Closed connection 374
10:46:07 INFO - 1500399967128 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49929
10:46:07 INFO - 1500399967129 Marionette DEBUG Closed connection 375
10:46:07 INFO - 1500399967129 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49930
10:46:07 INFO - 1500399967130 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:46:07 INFO - 1500399967131 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967134 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967138 Marionette TRACE 376 <- [1,1,null,{"sessionId":"0e24546c-6721-4e8a-b822-aeaa2bbb6eac","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967139 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:46:07 INFO - 1500399967141 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:46:07 INFO - 1500399967143 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:46:07 INFO - 1500399967144 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:46:07 INFO - 1500399967151 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:46:07 INFO - 1500399967153 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:46:07 INFO - 1500399967163 Marionette TRACE 376 <- [1,2,null,{}]
10:46:07 INFO - 1500399967168 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:46:07 INFO - 1500399967176 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"94367f76-d67b-4d42-87e4-321d5e31e09a","ELEMENT":"94367f76-d67b-4d42-87e4-321d5e31e09a"}}]
10:46:07 INFO - 1500399967178 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"94367f76-d67b-4d42-87e4-321d5e31e09a"}]
10:46:07 INFO - 1500399967419 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:46:07 INFO - 1500399967420 Marionette TRACE 376 <- [1,4,null,{}]
10:46:07 INFO - 1500399967422 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:46:07 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500399967425 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:46:07 INFO - 1500399967427 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:46:07 INFO - 1500399967428 Marionette TRACE 376 <- [1,6,null,{}]
10:46:07 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 357ms
10:46:07 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:46:07 INFO - 1500399967430 Marionette DEBUG Closed connection 376
10:46:07 INFO - 1500399967431 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49931
10:46:07 INFO - 1500399967432 Marionette DEBUG Closed connection 377
10:46:07 INFO - 1500399967432 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49932
10:46:07 INFO - 1500399967433 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:07 INFO - 1500399967434 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967437 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967441 Marionette TRACE 378 <- [1,1,null,{"sessionId":"75042eb0-2080-4a77-ae02-e99366318d3e","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967442 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:46:07 INFO - 1500399967443 Marionette TRACE 378 <- [1,2,null,{}]
10:46:07 INFO - 1500399967444 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:46:07 INFO - 1500399967444 Marionette TRACE 378 <- [1,3,null,{}]
10:46:07 INFO - 1500399967445 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:46:07 INFO - 1500399967446 Marionette TRACE 378 <- [1,4,null,{}]
10:46:07 INFO - 1500399967448 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:46:07 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500399967451 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:46:07 INFO - 1500399967452 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:46:07 INFO - 1500399967453 Marionette TRACE 378 <- [1,6,null,{}]
10:46:07 INFO - 1500399967455 Marionette DEBUG Closed connection 378
10:46:07 INFO - 1500399967456 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49933
10:46:07 INFO - 1500399967457 Marionette DEBUG Closed connection 379
10:46:07 INFO - 1500399967458 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49934
10:46:07 INFO - 1500399967459 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:07 INFO - 1500399967459 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967462 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967466 Marionette TRACE 380 <- [1,1,null,{"sessionId":"421fd9ee-b986-4f50-9e76-4d3e4c39ed0a","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967467 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:46:07 INFO - 1500399967469 Marionette TRACE 380 <- [1,2,null,{}]
10:46:07 INFO - 1500399967470 Marionette DEBUG Closed connection 380
10:46:07 INFO - 1500399967471 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49935
10:46:07 INFO - 1500399967472 Marionette DEBUG Closed connection 381
10:46:07 INFO - 1500399967473 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49936
10:46:07 INFO - 1500399967474 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:46:07 INFO - 1500399967475 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967478 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967481 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ed126393-d46b-42c3-bb9b-baa2f42b4485","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967482 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:46:07 INFO - 1500399967484 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:46:07 INFO - 1500399967487 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:46:07 INFO - 1500399967487 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:46:07 INFO - 1500399967494 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:46:07 INFO - 1500399967498 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:46:07 INFO - 1500399967503 Marionette TRACE 382 <- [1,2,null,{}]
10:46:07 INFO - 1500399967515 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:46:07 INFO - 1500399967520 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c6f9fb6a-c893-4387-88cc-c2ad4bf7a102","ELEMENT":"c6f9fb6a-c893-4387-88cc-c2ad4bf7a102"}}]
10:46:07 INFO - 1500399967521 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c6f9fb6a-c893-4387-88cc-c2ad4bf7a102"}]
10:46:07 INFO - 1500399967767 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:46:07 INFO - 1500399967771 Marionette TRACE 382 <- [1,4,null,{}]
10:46:07 INFO - 1500399967773 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:46:07 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500399967776 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:46:07 INFO - 1500399967777 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:46:07 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 351ms
10:46:07 INFO - TEST-START | test_click.py TestClick.test_input_file
10:46:07 INFO - 1500399967779 Marionette TRACE 382 <- [1,6,null,{}]
10:46:07 INFO - 1500399967781 Marionette DEBUG Closed connection 382
10:46:07 INFO - 1500399967782 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49937
10:46:07 INFO - 1500399967782 Marionette DEBUG Closed connection 383
10:46:07 INFO - 1500399967783 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49938
10:46:07 INFO - 1500399967784 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:07 INFO - 1500399967785 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967788 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967792 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5976c1b5-448f-4494-b36e-a568284b7a7a","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967793 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:46:07 INFO - 1500399967793 Marionette TRACE 384 <- [1,2,null,{}]
10:46:07 INFO - 1500399967794 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:46:07 INFO - 1500399967795 Marionette TRACE 384 <- [1,3,null,{}]
10:46:07 INFO - 1500399967796 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:46:07 INFO - 1500399967796 Marionette TRACE 384 <- [1,4,null,{}]
10:46:07 INFO - 1500399967798 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:46:07 INFO - TEST-START: test_click.py TestClick.test_input_file1500399967801 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:46:07 INFO - 1500399967802 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:46:07 INFO - 1500399967804 Marionette TRACE 384 <- [1,6,null,{}]
10:46:07 INFO - 1500399967805 Marionette DEBUG Closed connection 384
10:46:07 INFO - 1500399967806 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49939
10:46:07 INFO - 1500399967807 Marionette DEBUG Closed connection 385
10:46:07 INFO - 1500399967808 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49940
10:46:07 INFO - 1500399967809 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:07 INFO - 1500399967810 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967813 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967817 Marionette TRACE 386 <- [1,1,null,{"sessionId":"4c5ce8b5-5e87-425e-8cc3-d8bc428d9733","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967818 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:46:07 INFO - 1500399967819 Marionette TRACE 386 <- [1,2,null,{}]
10:46:07 INFO - 1500399967821 Marionette DEBUG Closed connection 386
10:46:07 INFO - 1500399967822 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49941
10:46:07 INFO - 1500399967824 Marionette DEBUG Closed connection 387
10:46:07 INFO - 1500399967825 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49942
10:46:07 INFO - 1500399967826 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:46:07 INFO - 1500399967827 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967830 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967833 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e2b08d74-e440-4224-9091-d04ae45fdb27","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967835 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:46:07 INFO - 1500399967837 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:46:07 INFO - 1500399967840 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:46:07 INFO - 1500399967841 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:46:07 INFO - 1500399967850 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:46:07 INFO - 1500399967857 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:46:07 INFO - 1500399967863 Marionette TRACE 388 <- [1,2,null,{}]
10:46:07 INFO - 1500399967868 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:46:07 INFO - 1500399967871 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"328b31ce-cdfe-434b-b8be-50f880a5e161","ELEMENT":"328b31ce-cdfe-434b-b8be-50f880a5e161"}}]
10:46:07 INFO - 1500399967877 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"328b31ce-cdfe-434b-b8be-50f880a5e161"}]
10:46:07 INFO - 1500399967905 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:46:07 INFO - 1500399967907 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:46:07 INFO - TEST-END: test_click.py TestClick.test_input_file1500399967910 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:46:07 INFO - 1500399967911 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:46:07 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 134ms
10:46:07 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:46:07 INFO - 1500399967913 Marionette TRACE 388 <- [1,6,null,{}]
10:46:07 INFO - 1500399967915 Marionette DEBUG Closed connection 388
10:46:07 INFO - 1500399967916 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49943
10:46:07 INFO - 1500399967916 Marionette DEBUG Closed connection 389
10:46:07 INFO - 1500399967917 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49944
10:46:07 INFO - 1500399967918 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:07 INFO - 1500399967919 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967922 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967926 Marionette TRACE 390 <- [1,1,null,{"sessionId":"a99137b3-d7d6-4f6f-9789-9e3d8922dd0a","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967927 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:46:07 INFO - 1500399967928 Marionette TRACE 390 <- [1,2,null,{}]
10:46:07 INFO - 1500399967929 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:46:07 INFO - 1500399967929 Marionette TRACE 390 <- [1,3,null,{}]
10:46:07 INFO - 1500399967930 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:46:07 INFO - 1500399967931 Marionette TRACE 390 <- [1,4,null,{}]
10:46:07 INFO - 1500399967932 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:46:07 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500399967935 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:46:07 INFO - 1500399967938 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:46:07 INFO - 1500399967940 Marionette TRACE 390 <- [1,6,null,{}]
10:46:07 INFO - 1500399967942 Marionette DEBUG Closed connection 390
10:46:07 INFO - 1500399967942 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49945
10:46:07 INFO - 1500399967943 Marionette DEBUG Closed connection 391
10:46:07 INFO - 1500399967944 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49946
10:46:07 INFO - 1500399967945 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:07 INFO - 1500399967946 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967949 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967952 Marionette TRACE 392 <- [1,1,null,{"sessionId":"6fefd283-cb5c-4744-99b2-d537b8b94cc0","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967953 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:46:07 INFO - 1500399967955 Marionette TRACE 392 <- [1,2,null,{}]
10:46:07 INFO - 1500399967957 Marionette DEBUG Closed connection 392
10:46:07 INFO - 1500399967958 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49947
10:46:07 INFO - 1500399967958 Marionette DEBUG Closed connection 393
10:46:07 INFO - 1500399967959 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49948
10:46:07 INFO - 1500399967960 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:46:07 INFO - 1500399967961 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:07 INFO - 1500399967964 Marionette DEBUG Register listener.js for window 2147483652
10:46:07 INFO - 1500399967968 Marionette TRACE 394 <- [1,1,null,{"sessionId":"77dd0d8c-8d4e-4a10-854d-db12865a3eba","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:07 INFO - 1500399967969 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:46:07 INFO - 1500399967971 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:46:07 INFO - 1500399967973 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:46:07 INFO - 1500399967974 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:46:07 INFO - 1500399967981 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:46:07 INFO - 1500399967984 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:46:07 INFO - 1500399967987 Marionette TRACE 394 <- [1,2,null,{}]
10:46:08 INFO - 1500399967999 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:46:08 INFO - 1500399968002 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"34d4ff86-ee65-439e-bc2c-64530cc726c4","ELEMENT":"34d4ff86-ee65-439e-bc2c-64530cc726c4"}}]
10:46:08 INFO - 1500399968003 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:46:08 INFO - 1500399968011 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"417cbb68-1f96-4594-99d0-ea7ac5332ac6","ELEMENT":"417cbb68-1f96-4594-99d0-ea7ac5332ac6"}}]
10:46:08 INFO - 1500399968013 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"34d4ff86-ee65-439e-bc2c-64530cc726c4"}]
10:46:08 INFO - 1500399968259 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:46:08 INFO - 1500399968260 Marionette TRACE 394 <- [1,5,null,{}]
10:46:08 INFO - 1500399968261 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"417cbb68-1f96-4594-99d0-ea7ac5332ac6"}]
10:46:08 INFO - 1500399968280 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:46:08 INFO - 1500399968282 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
10:46:08 INFO - 1500399968285 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:46:08 INFO - 1500399968287 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:46:08 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500399968290 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:46:08 INFO - 1500399968291 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:46:08 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 379ms
10:46:08 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:46:08 INFO - 1500399968293 Marionette TRACE 394 <- [1,9,null,{}]
10:46:08 INFO - 1500399968294 Marionette DEBUG Closed connection 394
10:46:08 INFO - 1500399968295 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49949
10:46:08 INFO - 1500399968296 Marionette DEBUG Closed connection 395
10:46:08 INFO - 1500399968297 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49950
10:46:08 INFO - 1500399968298 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:08 INFO - 1500399968299 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:08 INFO - 1500399968302 Marionette DEBUG Register listener.js for window 2147483652
10:46:08 INFO - 1500399968305 Marionette TRACE 396 <- [1,1,null,{"sessionId":"bbd62dc0-410d-462c-9e6a-4b8aac358ef9","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:08 INFO - 1500399968306 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:46:08 INFO - 1500399968307 Marionette TRACE 396 <- [1,2,null,{}]
10:46:08 INFO - 1500399968308 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:46:08 INFO - 1500399968308 Marionette TRACE 396 <- [1,3,null,{}]
10:46:08 INFO - 1500399968309 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:46:08 INFO - 1500399968310 Marionette TRACE 396 <- [1,4,null,{}]
10:46:08 INFO - 1500399968312 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:46:08 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500399968315 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:46:08 INFO - 1500399968316 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:46:08 INFO - 1500399968317 Marionette TRACE 396 <- [1,6,null,{}]
10:46:08 INFO - 1500399968319 Marionette DEBUG Closed connection 396
10:46:08 INFO - 1500399968319 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49951
10:46:08 INFO - 1500399968320 Marionette DEBUG Closed connection 397
10:46:08 INFO - 1500399968321 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49952
10:46:08 INFO - 1500399968322 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:46:08 INFO - 1500399968323 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:08 INFO - 1500399968326 Marionette DEBUG Register listener.js for window 2147483652
10:46:08 INFO - 1500399968329 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d93ab355-2e86-4818-99e3-2a849c212375","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:08 INFO - 1500399968330 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:46:08 INFO - 1500399968332 Marionette TRACE 398 <- [1,2,null,{}]
10:46:08 INFO - 1500399968333 Marionette DEBUG Closed connection 398
10:46:08 INFO - 1500399968334 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49953
10:46:08 INFO - 1500399968335 Marionette DEBUG Closed connection 399
10:46:08 INFO - 1500399968336 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49954
10:46:08 INFO - 1500399968337 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:46:08 INFO - 1500399968338 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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}
10:46:08 INFO - 1500399968340 Marionette DEBUG Register listener.js for window 2147483652
10:46:08 INFO - 1500399968344 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a24508ff-cba4-4ea5-9c3c-425b8c3f7f17","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":5704,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplwj0ay.mozrunner","moz:accessibilityChecks":false}}]
10:46:08 INFO - 1500399968345 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:46:08 INFO - 1500399968347 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:46:08 INFO - 1500399968349 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:46:08 INFO - 1500399968350 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:46:08 INFO - 1500399968359 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:46:08 INFO - 1500399968361 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:46:08 INFO - 1500399968370 Marionette TRACE 400 <- [1,2,null,{}]
10:46:08 INFO - 1500399968379 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:46:08 INFO - 1500399968384 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6901fa67-c302-42f2-848f-7f11535066c1","ELEMENT":"6901fa67-c302-42f2-848f-7f11535066c1"}}]
10:46:08 INFO - 1500399968385 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6901fa67-c302-42f2-848f-7f11535066c1"}]
10:46:08 INFO - 1500399968409 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:46:08 INFO - 1500399968410 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"6901fa67-c302-42f2-848f-7f11535066c1"}]
10:46:08 INFO - 1500399968430 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element