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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:28:20 INFO - 1499430500965 Marionette TRACE 353 -> [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:28:20 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499430500967 Marionette TRACE 353 <- [1,5,null,{"value":null}]
05:28:20 INFO - 1499430500968 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
05:28:20 INFO - 1499430500969 Marionette TRACE 353 <- [1,6,null,{}]
05:28:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 125ms
05:28:20 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:28:20 INFO - 1499430500971 Marionette DEBUG Closed connection 353
05:28:20 INFO - 1499430500972 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49774
05:28:20 INFO - 1499430500973 Marionette DEBUG Closed connection 354
05:28:20 INFO - 1499430500974 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49775
05:28:20 INFO - 1499430500975 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:20 INFO - 1499430500975 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:20 INFO - 1499430500977 Marionette DEBUG Register listener.js for window 12
05:28:20 INFO - 1499430500987 Marionette TRACE 355 <- [1,1,null,{"sessionId":"07d40746-36fc-4b4f-a0cf-bfac3647063f","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:20 INFO - 1499430500989 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
05:28:20 INFO - 1499430500989 Marionette TRACE 355 <- [1,2,null,{}]
05:28:20 INFO - 1499430500990 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:28:20 INFO - 1499430500991 Marionette TRACE 355 <- [1,3,null,{}]
05:28:20 INFO - 1499430500991 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
05:28:20 INFO - 1499430500992 Marionette TRACE 355 <- [1,4,null,{}]
05:28:20 INFO - 1499430500993 Marionette TRACE 355 -> [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:28:20 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499430500996 Marionette TRACE 355 <- [1,5,null,{"value":null}]
05:28:20 INFO - 1499430500997 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
05:28:21 INFO - 1499430500998 Marionette TRACE 355 <- [1,6,null,{}]
05:28:21 INFO - 1499430501000 Marionette DEBUG Closed connection 355
05:28:21 INFO - 1499430501001 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49776
05:28:21 INFO - 1499430501001 Marionette DEBUG Closed connection 356
05:28:21 INFO - 1499430501002 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49777
05:28:21 INFO - 1499430501003 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:21 INFO - 1499430501003 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501005 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501008 Marionette TRACE 357 <- [1,1,null,{"sessionId":"c353301c-375e-4e63-a7d7-24f00a848817","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501010 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
05:28:21 INFO - 1499430501011 Marionette TRACE 357 <- [1,2,null,{}]
05:28:21 INFO - 1499430501012 Marionette DEBUG Closed connection 357
05:28:21 INFO - 1499430501013 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49778
05:28:21 INFO - 1499430501014 Marionette DEBUG Closed connection 358
05:28:21 INFO - 1499430501014 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49779
05:28:21 INFO - 1499430501015 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:28:21 INFO - 1499430501016 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501017 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501020 Marionette TRACE 359 <- [1,1,null,{"sessionId":"530df897-62b9-4daf-8fac-ccde0109e974","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501022 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49345/clicks.html"}]
05:28:21 INFO - 1499430501023 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49345/hidden.html"
05:28:21 INFO - 1499430501028 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49345/hidden.html"
05:28:21 INFO - 1499430501028 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49345/hidden.html"
05:28:21 INFO - 1499430501047 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49345/clicks.html"
05:28:21 INFO - 1499430501048 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49345/clicks.html"
05:28:21 INFO - 1499430501051 Marionette TRACE 359 <- [1,2,null,{}]
05:28:21 INFO - 1499430501053 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:28:21 INFO - 1499430501062 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6cdd7048-70c5-4248-8c39-c30effa66385","ELEMENT":"6cdd7048-70c5-4248-8c39-c30effa66385"}}]
05:28:21 INFO - 1499430501063 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"6cdd7048-70c5-4248-8c39-c30effa66385"}]
05:28:21 INFO - 1499430501103 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49345/clicks.html"
05:28:21 INFO - 1499430501110 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49345/clicks.html"
05:28:21 INFO - 1499430501111 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49345/clicks.html"
05:28:21 INFO - 1499430501128 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49345/test.html"
05:28:21 INFO - 1499430501129 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49345/test.html"
05:28:21 INFO - 1499430501132 Marionette TRACE 359 <- [1,4,null,{}]
05:28:21 INFO - 1499430501133 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:28:21 INFO - 1499430501137 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f634aa8c-4f4d-4585-ba4c-a3bc64e06625","ELEMENT":"f634aa8c-4f4d-4585-ba4c-a3bc64e06625"}}]
05:28:21 INFO - 1499430501138 Marionette TRACE 359 -> [0,6,"getTitle",{}]
05:28:21 INFO - 1499430501138 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
05:28:21 INFO - 1499430501147 Marionette TRACE 359 -> [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:28:21 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499430501149 Marionette TRACE 359 <- [1,7,null,{"value":null}]
05:28:21 INFO - 1499430501150 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
05:28:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 182ms
05:28:21 INFO - 1499430501151 Marionette TRACE 359 <- [1,8,null,{}]
05:28:21 INFO - TEST-START | test_click.py TestClick.test_container_element
05:28:21 INFO - 1499430501153 Marionette DEBUG Closed connection 359
05:28:21 INFO - 1499430501154 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49782
05:28:21 INFO - 1499430501155 Marionette DEBUG Closed connection 360
05:28:21 INFO - 1499430501156 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49783
05:28:21 INFO - 1499430501157 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:21 INFO - 1499430501158 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501160 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501162 Marionette TRACE 361 <- [1,1,null,{"sessionId":"3a6518d3-d1db-4508-9e9b-a4fdaa3cd868","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501164 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
05:28:21 INFO - 1499430501165 Marionette TRACE 361 <- [1,2,null,{}]
05:28:21 INFO - 1499430501165 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:28:21 INFO - 1499430501166 Marionette TRACE 361 <- [1,3,null,{}]
05:28:21 INFO - 1499430501167 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
05:28:21 INFO - 1499430501167 Marionette TRACE 361 <- [1,4,null,{}]
05:28:21 INFO - 1499430501169 Marionette TRACE 361 -> [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:28:21 INFO - TEST-START: test_click.py TestClick.test_container_element1499430501171 Marionette TRACE 361 <- [1,5,null,{"value":null}]
05:28:21 INFO - 1499430501172 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
05:28:21 INFO - 1499430501173 Marionette TRACE 361 <- [1,6,null,{}]
05:28:21 INFO - 1499430501175 Marionette DEBUG Closed connection 361
05:28:21 INFO - 1499430501176 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49784
05:28:21 INFO - 1499430501176 Marionette DEBUG Closed connection 362
05:28:21 INFO - 1499430501177 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49785
05:28:21 INFO - 1499430501178 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:21 INFO - 1499430501178 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501180 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501183 Marionette TRACE 363 <- [1,1,null,{"sessionId":"9920b8cd-5e85-4bc8-b1ad-e135908a7047","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501185 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
05:28:21 INFO - 1499430501186 Marionette TRACE 363 <- [1,2,null,{}]
05:28:21 INFO - 1499430501188 Marionette DEBUG Closed connection 363
05:28:21 INFO - 1499430501188 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49786
05:28:21 INFO - 1499430501189 Marionette DEBUG Closed connection 364
05:28:21 INFO - 1499430501190 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49787
05:28:21 INFO - 1499430501191 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:28:21 INFO - 1499430501191 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501193 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501195 Marionette TRACE 365 <- [1,1,null,{"sessionId":"764f9f44-7417-488f-bf51-e2bbccd05c88","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501197 Marionette TRACE 365 -> [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:28:21 INFO - 1499430501199 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49345/test.html"
05:28:21 INFO - 1499430501202 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49345/test.html"
05:28:21 INFO - 1499430501202 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49345/test.html"
05:28:21 INFO - 1499430501230 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:28:21 INFO - 1499430501236 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:28:21 INFO - 1499430501240 Marionette TRACE 365 <- [1,2,null,{}]
05:28:21 INFO - 1499430501247 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:28:21 INFO - 1499430501249 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"04c125fb-b417-46b8-b52c-bc7a4eda21d2","ELEMENT":"04c125fb-b417-46b8-b52c-bc7a4eda21d2"}}]
05:28:21 INFO - 1499430501250 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"04c125fb-b417-46b8-b52c-bc7a4eda21d2"}]
05:28:21 INFO - 1499430501488 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:28:21 INFO - 1499430501490 Marionette TRACE 365 <- [1,4,null,{}]
05:28:21 INFO - 1499430501491 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"04c125fb-b417-46b8-b52c-bc7a4eda21d2","name":"selected"}]
05:28:21 INFO - 1499430501496 Marionette TRACE 365 <- [1,5,null,{"value":true}]
05:28:21 INFO - 1499430501498 Marionette TRACE 365 -> [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:28:21 INFO - TEST-END: test_click.py TestClick.test_container_element1499430501500 Marionette TRACE 365 <- [1,6,null,{"value":null}]
05:28:21 INFO - 1499430501501 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
05:28:21 INFO - 1499430501502 Marionette TRACE 365 <- [1,7,null,{}]
05:28:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 351ms
05:28:21 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:28:21 INFO - 1499430501504 Marionette DEBUG Closed connection 365
05:28:21 INFO - 1499430501505 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49790
05:28:21 INFO - 1499430501505 Marionette DEBUG Closed connection 366
05:28:21 INFO - 1499430501506 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49791
05:28:21 INFO - 1499430501507 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:21 INFO - 1499430501508 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501510 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501512 Marionette TRACE 367 <- [1,1,null,{"sessionId":"42cb4293-1e40-4277-93f0-2a7711cc854b","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501514 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
05:28:21 INFO - 1499430501514 Marionette TRACE 367 <- [1,2,null,{}]
05:28:21 INFO - 1499430501515 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:28:21 INFO - 1499430501516 Marionette TRACE 367 <- [1,3,null,{}]
05:28:21 INFO - 1499430501516 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
05:28:21 INFO - 1499430501517 Marionette TRACE 367 <- [1,4,null,{}]
05:28:21 INFO - 1499430501518 Marionette TRACE 367 -> [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:28:21 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499430501521 Marionette TRACE 367 <- [1,5,null,{"value":null}]
05:28:21 INFO - 1499430501522 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
05:28:21 INFO - 1499430501523 Marionette TRACE 367 <- [1,6,null,{}]
05:28:21 INFO - 1499430501525 Marionette DEBUG Closed connection 367
05:28:21 INFO - 1499430501526 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49792
05:28:21 INFO - 1499430501526 Marionette DEBUG Closed connection 368
05:28:21 INFO - 1499430501527 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49793
05:28:21 INFO - 1499430501528 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:21 INFO - 1499430501536 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501538 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501541 Marionette TRACE 369 <- [1,1,null,{"sessionId":"598680d8-6ce2-4af2-8443-7867eeb546ca","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501543 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
05:28:21 INFO - 1499430501544 Marionette TRACE 369 <- [1,2,null,{}]
05:28:21 INFO - 1499430501546 Marionette DEBUG Closed connection 369
05:28:21 INFO - 1499430501546 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49794
05:28:21 INFO - 1499430501547 Marionette DEBUG Closed connection 370
05:28:21 INFO - 1499430501548 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49795
05:28:21 INFO - 1499430501549 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:28:21 INFO - 1499430501549 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501551 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501553 Marionette TRACE 371 <- [1,1,null,{"sessionId":"97778b5c-c5e1-404d-be6a-7585b74091ae","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501555 Marionette TRACE 371 -> [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:28:21 INFO - 1499430501557 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:28:21 INFO - 1499430501559 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:28:21 INFO - 1499430501559 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:28:21 INFO - 1499430501569 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:28:21 INFO - 1499430501573 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:28:21 INFO - 1499430501581 Marionette TRACE 371 <- [1,2,null,{}]
05:28:21 INFO - 1499430501583 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:28:21 INFO - 1499430501585 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"98284dba-9ef5-4394-b14a-4a489efefbcd","ELEMENT":"98284dba-9ef5-4394-b14a-4a489efefbcd"}}]
05:28:21 INFO - 1499430501586 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"98284dba-9ef5-4394-b14a-4a489efefbcd"}]
05:28:21 INFO - 1499430501823 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:28:21 INFO - 1499430501824 Marionette TRACE 371 <- [1,4,null,{}]
05:28:21 INFO - 1499430501825 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"98284dba-9ef5-4394-b14a-4a489efefbcd","name":"selected"}]
05:28:21 INFO - 1499430501829 Marionette TRACE 371 <- [1,5,null,{"value":true}]
05:28:21 INFO - 1499430501830 Marionette TRACE 371 -> [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:28:21 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499430501833 Marionette TRACE 371 <- [1,6,null,{"value":null}]
05:28:21 INFO - 1499430501834 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
05:28:21 INFO - 1499430501835 Marionette TRACE 371 <- [1,7,null,{}]
05:28:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 333ms
05:28:21 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:28:21 INFO - 1499430501837 Marionette DEBUG Closed connection 371
05:28:21 INFO - 1499430501838 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49796
05:28:21 INFO - 1499430501838 Marionette DEBUG Closed connection 372
05:28:21 INFO - 1499430501839 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49797
05:28:21 INFO - 1499430501840 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:21 INFO - 1499430501841 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501843 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501845 Marionette TRACE 373 <- [1,1,null,{"sessionId":"ad59bf36-869a-4419-9794-d14f1d1f04ad","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501847 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
05:28:21 INFO - 1499430501847 Marionette TRACE 373 <- [1,2,null,{}]
05:28:21 INFO - 1499430501848 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:28:21 INFO - 1499430501849 Marionette TRACE 373 <- [1,3,null,{}]
05:28:21 INFO - 1499430501849 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
05:28:21 INFO - 1499430501850 Marionette TRACE 373 <- [1,4,null,{}]
05:28:21 INFO - 1499430501852 Marionette TRACE 373 -> [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:28:21 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499430501854 Marionette TRACE 373 <- [1,5,null,{"value":null}]
05:28:21 INFO - 1499430501855 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
05:28:21 INFO - 1499430501856 Marionette TRACE 373 <- [1,6,null,{}]
05:28:21 INFO - 1499430501857 Marionette DEBUG Closed connection 373
05:28:21 INFO - 1499430501858 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49798
05:28:21 INFO - 1499430501859 Marionette DEBUG Closed connection 374
05:28:21 INFO - 1499430501859 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49799
05:28:21 INFO - 1499430501860 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:21 INFO - 1499430501861 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501863 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501865 Marionette TRACE 375 <- [1,1,null,{"sessionId":"b9c205a4-34db-413f-b5fa-9e1d9ba16a2c","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501867 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
05:28:21 INFO - 1499430501868 Marionette TRACE 375 <- [1,2,null,{}]
05:28:21 INFO - 1499430501870 Marionette DEBUG Closed connection 375
05:28:21 INFO - 1499430501871 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49800
05:28:21 INFO - 1499430501871 Marionette DEBUG Closed connection 376
05:28:21 INFO - 1499430501872 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49801
05:28:21 INFO - 1499430501873 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:28:21 INFO - 1499430501874 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:21 INFO - 1499430501876 Marionette DEBUG Register listener.js for window 12
05:28:21 INFO - 1499430501878 Marionette TRACE 377 <- [1,1,null,{"sessionId":"d1998041-e7a6-4bbb-9502-f065607d1d1a","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:21 INFO - 1499430501880 Marionette TRACE 377 -> [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:28:21 INFO - 1499430501882 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:28:21 INFO - 1499430501885 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:28:21 INFO - 1499430501885 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:28:21 INFO - 1499430501919 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:28:21 INFO - 1499430501923 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:28:21 INFO - 1499430501933 Marionette TRACE 377 <- [1,2,null,{}]
05:28:21 INFO - 1499430501934 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:28:21 INFO - 1499430501937 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa4d8391-10b8-47be-91e5-afccde9058bb","ELEMENT":"aa4d8391-10b8-47be-91e5-afccde9058bb"}}]
05:28:21 INFO - 1499430501937 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"aa4d8391-10b8-47be-91e5-afccde9058bb"}]
05:28:22 INFO - 1499430502172 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:28:22 INFO - 1499430502173 Marionette TRACE 377 <- [1,4,null,{}]
05:28:22 INFO - 1499430502175 Marionette TRACE 377 -> [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:28:22 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499430502177 Marionette TRACE 377 <- [1,5,null,{"value":null}]
05:28:22 INFO - 1499430502178 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
05:28:22 INFO - 1499430502179 Marionette TRACE 377 <- [1,6,null,{}]
05:28:22 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 344ms
05:28:22 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:28:22 INFO - 1499430502181 Marionette DEBUG Closed connection 377
05:28:22 INFO - 1499430502182 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49802
05:28:22 INFO - 1499430502183 Marionette DEBUG Closed connection 378
05:28:22 INFO - 1499430502184 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49803
05:28:22 INFO - 1499430502185 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:22 INFO - 1499430502185 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502187 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502190 Marionette TRACE 379 <- [1,1,null,{"sessionId":"bbb2beae-311c-4315-b2d1-9679d7e40226","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502193 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
05:28:22 INFO - 1499430502194 Marionette TRACE 379 <- [1,2,null,{}]
05:28:22 INFO - 1499430502195 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:28:22 INFO - 1499430502195 Marionette TRACE 379 <- [1,3,null,{}]
05:28:22 INFO - 1499430502196 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
05:28:22 INFO - 1499430502196 Marionette TRACE 379 <- [1,4,null,{}]
05:28:22 INFO - 1499430502198 Marionette TRACE 379 -> [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:28:22 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499430502200 Marionette TRACE 379 <- [1,5,null,{"value":null}]
05:28:22 INFO - 1499430502201 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
05:28:22 INFO - 1499430502202 Marionette TRACE 379 <- [1,6,null,{}]
05:28:22 INFO - 1499430502204 Marionette DEBUG Closed connection 379
05:28:22 INFO - 1499430502204 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49804
05:28:22 INFO - 1499430502205 Marionette DEBUG Closed connection 380
05:28:22 INFO - 1499430502206 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49805
05:28:22 INFO - 1499430502207 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:22 INFO - 1499430502207 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502209 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502219 Marionette TRACE 381 <- [1,1,null,{"sessionId":"21337c9a-5d8d-48c0-8339-d6f44fd44036","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502222 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
05:28:22 INFO - 1499430502223 Marionette TRACE 381 <- [1,2,null,{}]
05:28:22 INFO - 1499430502225 Marionette DEBUG Closed connection 381
05:28:22 INFO - 1499430502226 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49806
05:28:22 INFO - 1499430502227 Marionette DEBUG Closed connection 382
05:28:22 INFO - 1499430502228 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49807
05:28:22 INFO - 1499430502229 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:28:22 INFO - 1499430502229 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502231 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502234 Marionette TRACE 383 <- [1,1,null,{"sessionId":"756b5d8e-6d58-41fe-8728-28d586ddfe42","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502236 Marionette TRACE 383 -> [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:28:22 INFO - 1499430502237 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:28:22 INFO - 1499430502239 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:28:22 INFO - 1499430502240 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:28:22 INFO - 1499430502255 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:28:22 INFO - 1499430502260 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:28:22 INFO - 1499430502262 Marionette TRACE 383 <- [1,2,null,{}]
05:28:22 INFO - 1499430502264 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:28:22 INFO - 1499430502266 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"85ea167e-c93f-45e2-acc2-0719d3121f58","ELEMENT":"85ea167e-c93f-45e2-acc2-0719d3121f58"}}]
05:28:22 INFO - 1499430502267 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"85ea167e-c93f-45e2-acc2-0719d3121f58"}]
05:28:22 INFO - 1499430502511 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:28:22 INFO - 1499430502512 Marionette TRACE 383 <- [1,4,null,{}]
05:28:22 INFO - 1499430502514 Marionette TRACE 383 -> [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:28:22 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499430502516 Marionette TRACE 383 <- [1,5,null,{"value":null}]
05:28:22 INFO - 1499430502517 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
05:28:22 INFO - 1499430502518 Marionette TRACE 383 <- [1,6,null,{}]
05:28:22 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 339ms
05:28:22 INFO - TEST-START | test_click.py TestClick.test_input_file
05:28:22 INFO - 1499430502520 Marionette DEBUG Closed connection 383
05:28:22 INFO - 1499430502521 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49808
05:28:22 INFO - 1499430502522 Marionette DEBUG Closed connection 384
05:28:22 INFO - 1499430502523 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49809
05:28:22 INFO - 1499430502524 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:22 INFO - 1499430502524 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502526 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502529 Marionette TRACE 385 <- [1,1,null,{"sessionId":"7b09ac65-0da7-49f7-8c60-3982585e99fa","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502531 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
05:28:22 INFO - 1499430502531 Marionette TRACE 385 <- [1,2,null,{}]
05:28:22 INFO - 1499430502532 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:28:22 INFO - 1499430502532 Marionette TRACE 385 <- [1,3,null,{}]
05:28:22 INFO - 1499430502533 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
05:28:22 INFO - 1499430502534 Marionette TRACE 385 <- [1,4,null,{}]
05:28:22 INFO - 1499430502535 Marionette TRACE 385 -> [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:28:22 INFO - TEST-START: test_click.py TestClick.test_input_file1499430502537 Marionette TRACE 385 <- [1,5,null,{"value":null}]
05:28:22 INFO - 1499430502539 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
05:28:22 INFO - 1499430502540 Marionette TRACE 385 <- [1,6,null,{}]
05:28:22 INFO - 1499430502541 Marionette DEBUG Closed connection 385
05:28:22 INFO - 1499430502542 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49810
05:28:22 INFO - 1499430502543 Marionette DEBUG Closed connection 386
05:28:22 INFO - 1499430502543 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49811
05:28:22 INFO - 1499430502544 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:22 INFO - 1499430502545 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502547 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502549 Marionette TRACE 387 <- [1,1,null,{"sessionId":"db9a8d45-6426-4d8c-904d-f3179e545952","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502551 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
05:28:22 INFO - 1499430502552 Marionette TRACE 387 <- [1,2,null,{}]
05:28:22 INFO - 1499430502554 Marionette DEBUG Closed connection 387
05:28:22 INFO - 1499430502554 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49812
05:28:22 INFO - 1499430502555 Marionette DEBUG Closed connection 388
05:28:22 INFO - 1499430502556 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49813
05:28:22 INFO - 1499430502557 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:28:22 INFO - 1499430502557 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502559 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502562 Marionette TRACE 389 <- [1,1,null,{"sessionId":"caed55be-c3ad-4bce-8749-af66214af9ec","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502564 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:28:22 INFO - 1499430502565 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:28:22 INFO - 1499430502567 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:28:22 INFO - 1499430502567 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:28:22 INFO - 1499430502585 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:28:22 INFO - 1499430502588 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:28:22 INFO - 1499430502597 Marionette TRACE 389 <- [1,2,null,{}]
05:28:22 INFO - 1499430502598 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:28:22 INFO - 1499430502600 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63af2cdb-6ee1-48c3-9109-be03a854c843","ELEMENT":"63af2cdb-6ee1-48c3-9109-be03a854c843"}}]
05:28:22 INFO - 1499430502601 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"63af2cdb-6ee1-48c3-9109-be03a854c843"}]
05:28:22 INFO - 1499430502626 Marionette TRACE 389 <- [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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:28:22 INFO - 1499430502628 Marionette TRACE 389 -> [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:28:22 INFO - TEST-END: test_click.py TestClick.test_input_file1499430502631 Marionette TRACE 389 <- [1,5,null,{"value":null}]
05:28:22 INFO - 1499430502632 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
05:28:22 INFO - 1499430502633 Marionette TRACE 389 <- [1,6,null,{}]
05:28:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 114ms
05:28:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:28:22 INFO - 1499430502634 Marionette DEBUG Closed connection 389
05:28:22 INFO - 1499430502636 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49814
05:28:22 INFO - 1499430502636 Marionette DEBUG Closed connection 390
05:28:22 INFO - 1499430502637 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49815
05:28:22 INFO - 1499430502638 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:22 INFO - 1499430502639 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502641 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502643 Marionette TRACE 391 <- [1,1,null,{"sessionId":"3e939ddb-e4f9-4160-8e05-53138d5df6c0","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502646 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
05:28:22 INFO - 1499430502646 Marionette TRACE 391 <- [1,2,null,{}]
05:28:22 INFO - 1499430502647 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:28:22 INFO - 1499430502647 Marionette TRACE 391 <- [1,3,null,{}]
05:28:22 INFO - 1499430502648 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
05:28:22 INFO - 1499430502649 Marionette TRACE 391 <- [1,4,null,{}]
05:28:22 INFO - 1499430502650 Marionette TRACE 391 -> [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:28:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499430502653 Marionette TRACE 391 <- [1,5,null,{"value":null}]
05:28:22 INFO - 1499430502653 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
05:28:22 INFO - 1499430502655 Marionette TRACE 391 <- [1,6,null,{}]
05:28:22 INFO - 1499430502656 Marionette DEBUG Closed connection 391
05:28:22 INFO - 1499430502657 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49816
05:28:22 INFO - 1499430502658 Marionette DEBUG Closed connection 392
05:28:22 INFO - 1499430502659 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49817
05:28:22 INFO - 1499430502660 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:22 INFO - 1499430502660 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502662 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502665 Marionette TRACE 393 <- [1,1,null,{"sessionId":"cfe68737-26f6-416b-b5ea-097232ed387a","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502667 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
05:28:22 INFO - 1499430502668 Marionette TRACE 393 <- [1,2,null,{}]
05:28:22 INFO - 1499430502669 Marionette DEBUG Closed connection 393
05:28:22 INFO - 1499430502670 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49818
05:28:22 INFO - 1499430502670 Marionette DEBUG Closed connection 394
05:28:22 INFO - 1499430502671 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49819
05:28:22 INFO - 1499430502672 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:28:22 INFO - 1499430502673 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502682 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502685 Marionette TRACE 395 <- [1,1,null,{"sessionId":"8d757fea-7154-4f8c-8ee2-92fa9ddeb822","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502687 Marionette TRACE 395 -> [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:28:22 INFO - 1499430502688 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:28:22 INFO - 1499430502691 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:28:22 INFO - 1499430502691 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:28:22 INFO - 1499430502701 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:28:22 INFO - 1499430502705 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:28:22 INFO - 1499430502714 Marionette TRACE 395 <- [1,2,null,{}]
05:28:22 INFO - 1499430502715 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:28:22 INFO - 1499430502717 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1d7449e7-5d77-4d86-933d-e49a7d59b7df","ELEMENT":"1d7449e7-5d77-4d86-933d-e49a7d59b7df"}}]
05:28:22 INFO - 1499430502718 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:28:22 INFO - 1499430502720 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6aff069f-8b9d-422c-a1b9-8aeb345b20d9","ELEMENT":"6aff069f-8b9d-422c-a1b9-8aeb345b20d9"}}]
05:28:22 INFO - 1499430502721 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"1d7449e7-5d77-4d86-933d-e49a7d59b7df"}]
05:28:22 INFO - 1499430502956 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:28:22 INFO - 1499430502957 Marionette TRACE 395 <- [1,5,null,{}]
05:28:22 INFO - 1499430502958 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"6aff069f-8b9d-422c-a1b9-8aeb345b20d9"}]
05:28:22 INFO - 1499430502972 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:28:22 INFO - 1499430502974 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:28:22 INFO - 1499430502976 Marionette TRACE 395 <- [1,7,null,{"value":false}]
05:28:22 INFO - 1499430502978 Marionette TRACE 395 -> [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:28:22 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499430502980 Marionette TRACE 395 <- [1,8,null,{"value":null}]
05:28:22 INFO - 1499430502981 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
05:28:22 INFO - 1499430502982 Marionette TRACE 395 <- [1,9,null,{}]
05:28:22 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 348ms
05:28:22 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:28:22 INFO - 1499430502983 Marionette DEBUG Closed connection 395
05:28:22 INFO - 1499430502984 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49820
05:28:22 INFO - 1499430502985 Marionette DEBUG Closed connection 396
05:28:22 INFO - 1499430502986 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49821
05:28:22 INFO - 1499430502987 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:22 INFO - 1499430502987 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:22 INFO - 1499430502989 Marionette DEBUG Register listener.js for window 12
05:28:22 INFO - 1499430502992 Marionette TRACE 397 <- [1,1,null,{"sessionId":"b59ebab4-08b7-4396-915a-e537c209a19e","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:22 INFO - 1499430502994 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
05:28:22 INFO - 1499430502994 Marionette TRACE 397 <- [1,2,null,{}]
05:28:22 INFO - 1499430502995 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:28:22 INFO - 1499430502995 Marionette TRACE 397 <- [1,3,null,{}]
05:28:22 INFO - 1499430502996 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
05:28:22 INFO - 1499430502997 Marionette TRACE 397 <- [1,4,null,{}]
05:28:23 INFO - 1499430502998 Marionette TRACE 397 -> [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:28:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499430503000 Marionette TRACE 397 <- [1,5,null,{"value":null}]
05:28:23 INFO - 1499430503001 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
05:28:23 INFO - 1499430503002 Marionette TRACE 397 <- [1,6,null,{}]
05:28:23 INFO - 1499430503004 Marionette DEBUG Closed connection 397
05:28:23 INFO - 1499430503004 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49822
05:28:23 INFO - 1499430503005 Marionette DEBUG Closed connection 398
05:28:23 INFO - 1499430503006 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49823
05:28:23 INFO - 1499430503007 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:28:23 INFO - 1499430503007 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:23 INFO - 1499430503009 Marionette DEBUG Register listener.js for window 12
05:28:23 INFO - 1499430503012 Marionette TRACE 399 <- [1,1,null,{"sessionId":"e2900e3f-da98-4779-8c87-cf949638578d","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:23 INFO - 1499430503014 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
05:28:23 INFO - 1499430503015 Marionette TRACE 399 <- [1,2,null,{}]
05:28:23 INFO - 1499430503017 Marionette DEBUG Closed connection 399
05:28:23 INFO - 1499430503017 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49824
05:28:23 INFO - 1499430503018 Marionette DEBUG Closed connection 400
05:28:23 INFO - 1499430503019 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49825
05:28:23 INFO - 1499430503020 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:28:23 INFO - 1499430503020 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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}
05:28:23 INFO - 1499430503022 Marionette DEBUG Register listener.js for window 12
05:28:23 INFO - 1499430503026 Marionette TRACE 401 <- [1,1,null,{"sessionId":"d23497d6-32cc-4b1f-9e57-0b92c9b76a07","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":1600,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmplbomky.mozrunner","moz:accessibilityChecks":false}}]
05:28:23 INFO - 1499430503028 Marionette TRACE 401 -> [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:28:23 INFO - 1499430503029 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:28:23 INFO - 1499430503031 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:28:23 INFO - 1499430503031 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:28:23 INFO - 1499430503049 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:28:23 INFO - 1499430503050 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:28:23 INFO - 1499430503053 Marionette TRACE 401 <- [1,2,null,{}]
05:28:23 INFO - 1499430503054 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:28:23 INFO - 1499430503062 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f1eafb29-e2f0-460c-8636-adef9383fd84","ELEMENT":"f1eafb29-e2f0-460c-8636-adef9383fd84"}}]
05:28:23 INFO - 1499430503063 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f1eafb29-e2f0-460c-8636-adef9383fd84"}]
05:28:23 INFO - 1499430503084 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
05:28:23 INFO - 1499430503085 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"f1eafb29-e2f0-460c-8636-adef9383fd84"}]
05:28:23 INFO - 1499430503099 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element