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]
13:02:57 INFO - 1499716977167 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}]
13:02:57 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499716977169 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:02:57 INFO - 1499716977170 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:02:57 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 120ms
13:02:57 INFO - 1499716977171 Marionette TRACE 352 <- [1,6,null,{}]
13:02:57 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:02:57 INFO - 1499716977172 Marionette DEBUG Closed connection 352
13:02:57 INFO - 1499716977173 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49774
13:02:57 INFO - 1499716977174 Marionette DEBUG Closed connection 353
13:02:57 INFO - 1499716977175 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49775
13:02:57 INFO - 1499716977175 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:57 INFO - 1499716977176 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977178 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977181 Marionette TRACE 354 <- [1,1,null,{"sessionId":"d6586422-736c-4bc8-b545-5370164251a1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977182 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:02:57 INFO - 1499716977182 Marionette TRACE 354 <- [1,2,null,{}]
13:02:57 INFO - 1499716977183 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:02:57 INFO - 1499716977183 Marionette TRACE 354 <- [1,3,null,{}]
13:02:57 INFO - 1499716977184 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:02:57 INFO - 1499716977185 Marionette TRACE 354 <- [1,4,null,{}]
13:02:57 INFO - 1499716977187 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}]
13:02:57 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499716977189 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:02:57 INFO - 1499716977190 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:02:57 INFO - 1499716977191 Marionette TRACE 354 <- [1,6,null,{}]
13:02:57 INFO - 1499716977192 Marionette DEBUG Closed connection 354
13:02:57 INFO - 1499716977193 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49776
13:02:57 INFO - 1499716977194 Marionette DEBUG Closed connection 355
13:02:57 INFO - 1499716977194 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49777
13:02:57 INFO - 1499716977195 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:57 INFO - 1499716977195 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977197 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977200 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5e7fc70e-845e-4b6e-a4ab-fef676019619","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977201 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:02:57 INFO - 1499716977203 Marionette TRACE 356 <- [1,2,null,{}]
13:02:57 INFO - 1499716977204 Marionette DEBUG Closed connection 356
13:02:57 INFO - 1499716977205 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49778
13:02:57 INFO - 1499716977205 Marionette DEBUG Closed connection 357
13:02:57 INFO - 1499716977206 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49779
13:02:57 INFO - 1499716977207 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:02:57 INFO - 1499716977207 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977209 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977219 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b4234b6b-9527-4d54-bbff-0d42f7f52dfd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977220 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49334/clicks.html"}]
13:02:57 INFO - 1499716977222 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/hidden.html"
13:02:57 INFO - 1499716977228 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/hidden.html"
13:02:57 INFO - 1499716977228 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/hidden.html"
13:02:57 INFO - 1499716977237 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/clicks.html"
13:02:57 INFO - 1499716977239 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/clicks.html"
13:02:57 INFO - 1499716977241 Marionette TRACE 358 <- [1,2,null,{}]
13:02:57 INFO - 1499716977244 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:02:57 INFO - 1499716977246 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c0dcbdb6-8845-4ce6-aeca-1eaea4b81e09","ELEMENT":"c0dcbdb6-8845-4ce6-aeca-1eaea4b81e09"}}]
13:02:57 INFO - 1499716977248 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"c0dcbdb6-8845-4ce6-aeca-1eaea4b81e09"}]
13:02:57 INFO - 1499716977287 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/clicks.html"
13:02:57 INFO - 1499716977294 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/clicks.html"
13:02:57 INFO - 1499716977295 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/clicks.html"
13:02:57 INFO - 1499716977308 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/test.html"
13:02:57 INFO - 1499716977309 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/test.html"
13:02:57 INFO - 1499716977314 Marionette TRACE 358 <- [1,4,null,{}]
13:02:57 INFO - 1499716977324 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:02:57 INFO - 1499716977328 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f7e7d5c7-2673-4eed-9db7-63e289d07824","ELEMENT":"f7e7d5c7-2673-4eed-9db7-63e289d07824"}}]
13:02:57 INFO - 1499716977329 Marionette TRACE 358 -> [0,6,"getTitle",{}]
13:02:57 INFO - 1499716977329 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
13:02:57 INFO - 1499716977331 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}]
13:02:57 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499716977333 Marionette TRACE 358 <- [1,7,null,{"value":null}]
13:02:57 INFO - 1499716977334 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
13:02:57 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 164ms
13:02:57 INFO - TEST-START | test_click.py TestClick.test_container_element
13:02:57 INFO - 1499716977335 Marionette TRACE 358 <- [1,8,null,{}]
13:02:57 INFO - 1499716977337 Marionette DEBUG Closed connection 358
13:02:57 INFO - 1499716977337 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49782
13:02:57 INFO - 1499716977338 Marionette DEBUG Closed connection 359
13:02:57 INFO - 1499716977339 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49783
13:02:57 INFO - 1499716977340 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:57 INFO - 1499716977340 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977342 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977345 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4f2afe6a-5463-4021-90bc-cb0323541a34","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977346 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:02:57 INFO - 1499716977347 Marionette TRACE 360 <- [1,2,null,{}]
13:02:57 INFO - 1499716977347 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:02:57 INFO - 1499716977348 Marionette TRACE 360 <- [1,3,null,{}]
13:02:57 INFO - 1499716977349 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:02:57 INFO - 1499716977349 Marionette TRACE 360 <- [1,4,null,{}]
13:02:57 INFO - 1499716977350 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}]
13:02:57 INFO - TEST-START: test_click.py TestClick.test_container_element1499716977353 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:02:57 INFO - 1499716977353 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:02:57 INFO - 1499716977355 Marionette TRACE 360 <- [1,6,null,{}]
13:02:57 INFO - 1499716977356 Marionette DEBUG Closed connection 360
13:02:57 INFO - 1499716977356 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49784
13:02:57 INFO - 1499716977357 Marionette DEBUG Closed connection 361
13:02:57 INFO - 1499716977358 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49785
13:02:57 INFO - 1499716977359 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:57 INFO - 1499716977359 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977361 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977364 Marionette TRACE 362 <- [1,1,null,{"sessionId":"625b0a70-57b1-47db-8380-4f78dd5832da","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977365 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
13:02:57 INFO - 1499716977366 Marionette TRACE 362 <- [1,2,null,{}]
13:02:57 INFO - 1499716977367 Marionette DEBUG Closed connection 362
13:02:57 INFO - 1499716977368 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49786
13:02:57 INFO - 1499716977369 Marionette DEBUG Closed connection 363
13:02:57 INFO - 1499716977369 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49787
13:02:57 INFO - 1499716977370 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:02:57 INFO - 1499716977371 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977373 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977376 Marionette TRACE 364 <- [1,1,null,{"sessionId":"da7f0648-b977-4090-8553-727e52efeab8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977377 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"}]
13:02:57 INFO - 1499716977378 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/test.html"
13:02:57 INFO - 1499716977381 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/test.html"
13:02:57 INFO - 1499716977381 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/test.html"
13:02:57 INFO - 1499716977389 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"
13:02:57 INFO - 1499716977394 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"
13:02:57 INFO - 1499716977396 Marionette TRACE 364 <- [1,2,null,{}]
13:02:57 INFO - 1499716977404 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:02:57 INFO - 1499716977406 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"040135ee-66fd-416e-b8b5-c56b92053055","ELEMENT":"040135ee-66fd-416e-b8b5-c56b92053055"}}]
13:02:57 INFO - 1499716977407 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"040135ee-66fd-416e-b8b5-c56b92053055"}]
13:02:57 INFO - 1499716977643 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:02:57 INFO - 1499716977644 Marionette TRACE 364 <- [1,4,null,{}]
13:02:57 INFO - 1499716977645 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"040135ee-66fd-416e-b8b5-c56b92053055","name":"selected"}]
13:02:57 INFO - 1499716977650 Marionette TRACE 364 <- [1,5,null,{"value":true}]
13:02:57 INFO - 1499716977651 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}]
13:02:57 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 320ms
13:02:57 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:02:57 INFO - TEST-END: test_click.py TestClick.test_container_element1499716977654 Marionette TRACE 364 <- [1,6,null,{"value":null}]
13:02:57 INFO - 1499716977655 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
13:02:57 INFO - 1499716977656 Marionette TRACE 364 <- [1,7,null,{}]
13:02:57 INFO - 1499716977658 Marionette DEBUG Closed connection 364
13:02:57 INFO - 1499716977658 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49788
13:02:57 INFO - 1499716977659 Marionette DEBUG Closed connection 365
13:02:57 INFO - 1499716977660 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49789
13:02:57 INFO - 1499716977661 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:57 INFO - 1499716977662 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977664 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977666 Marionette TRACE 366 <- [1,1,null,{"sessionId":"636e8f12-9822-4480-aee8-2b4b94eeb0f0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977668 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
13:02:57 INFO - 1499716977668 Marionette TRACE 366 <- [1,2,null,{}]
13:02:57 INFO - 1499716977669 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:02:57 INFO - 1499716977669 Marionette TRACE 366 <- [1,3,null,{}]
13:02:57 INFO - 1499716977670 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
13:02:57 INFO - 1499716977670 Marionette TRACE 366 <- [1,4,null,{}]
13:02:57 INFO - 1499716977672 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}]
13:02:57 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499716977674 Marionette TRACE 366 <- [1,5,null,{"value":null}]
13:02:57 INFO - 1499716977675 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
13:02:57 INFO - 1499716977676 Marionette TRACE 366 <- [1,6,null,{}]
13:02:57 INFO - 1499716977678 Marionette DEBUG Closed connection 366
13:02:57 INFO - 1499716977678 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49790
13:02:57 INFO - 1499716977679 Marionette DEBUG Closed connection 367
13:02:57 INFO - 1499716977680 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49791
13:02:57 INFO - 1499716977681 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:57 INFO - 1499716977681 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977683 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977686 Marionette TRACE 368 <- [1,1,null,{"sessionId":"8c72361e-bfc1-4e3e-a751-fcb8d57309d4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977687 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
13:02:57 INFO - 1499716977688 Marionette TRACE 368 <- [1,2,null,{}]
13:02:57 INFO - 1499716977689 Marionette DEBUG Closed connection 368
13:02:57 INFO - 1499716977690 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49792
13:02:57 INFO - 1499716977690 Marionette DEBUG Closed connection 369
13:02:57 INFO - 1499716977691 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49793
13:02:57 INFO - 1499716977692 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:02:57 INFO - 1499716977693 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977695 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977697 Marionette TRACE 370 <- [1,1,null,{"sessionId":"0d0df614-89b7-441a-b3f9-5e2b96a1309b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977698 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"}]
13:02:57 INFO - 1499716977700 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"
13:02:57 INFO - 1499716977702 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"
13:02:57 INFO - 1499716977702 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"
13:02:57 INFO - 1499716977707 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"
13:02:57 INFO - 1499716977710 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"
13:02:57 INFO - 1499716977712 Marionette TRACE 370 <- [1,2,null,{}]
13:02:57 INFO - 1499716977714 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:02:57 INFO - 1499716977721 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ddb846c-6025-40ba-930d-d26581a99a9d","ELEMENT":"8ddb846c-6025-40ba-930d-d26581a99a9d"}}]
13:02:57 INFO - 1499716977722 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"8ddb846c-6025-40ba-930d-d26581a99a9d"}]
13:02:57 INFO - 1499716977963 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:02:57 INFO - 1499716977964 Marionette TRACE 370 <- [1,4,null,{}]
13:02:57 INFO - 1499716977965 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"8ddb846c-6025-40ba-930d-d26581a99a9d","name":"selected"}]
13:02:57 INFO - 1499716977968 Marionette TRACE 370 <- [1,5,null,{"value":true}]
13:02:57 INFO - 1499716977970 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}]
13:02:57 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499716977976 Marionette TRACE 370 <- [1,6,null,{"value":null}]
13:02:57 INFO - 1499716977979 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
13:02:57 INFO - 1499716977980 Marionette TRACE 370 <- [1,7,null,{}]
13:02:57 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 325ms
13:02:57 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:02:57 INFO - 1499716977982 Marionette DEBUG Closed connection 370
13:02:57 INFO - 1499716977983 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49794
13:02:57 INFO - 1499716977984 Marionette DEBUG Closed connection 371
13:02:57 INFO - 1499716977985 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49795
13:02:57 INFO - 1499716977986 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:57 INFO - 1499716977986 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:57 INFO - 1499716977988 Marionette DEBUG Register listener.js for window 4294967297
13:02:57 INFO - 1499716977991 Marionette TRACE 372 <- [1,1,null,{"sessionId":"73e6b514-ebc4-4b35-ac82-742df1d19ade","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:57 INFO - 1499716977992 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
13:02:57 INFO - 1499716977992 Marionette TRACE 372 <- [1,2,null,{}]
13:02:57 INFO - 1499716977993 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:02:57 INFO - 1499716977994 Marionette TRACE 372 <- [1,3,null,{}]
13:02:57 INFO - 1499716977994 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
13:02:57 INFO - 1499716977995 Marionette TRACE 372 <- [1,4,null,{}]
13:02:57 INFO - 1499716977996 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}]
13:02:58 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499716977999 Marionette TRACE 372 <- [1,5,null,{"value":null}]
13:02:58 INFO - 1499716978000 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
13:02:58 INFO - 1499716978001 Marionette TRACE 372 <- [1,6,null,{}]
13:02:58 INFO - 1499716978003 Marionette DEBUG Closed connection 372
13:02:58 INFO - 1499716978003 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49796
13:02:58 INFO - 1499716978005 Marionette DEBUG Closed connection 373
13:02:58 INFO - 1499716978006 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49797
13:02:58 INFO - 1499716978006 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:58 INFO - 1499716978007 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978009 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978012 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f8cd2181-2afc-4a6f-86d1-2d4caa4e04bf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978013 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
13:02:58 INFO - 1499716978014 Marionette TRACE 374 <- [1,2,null,{}]
13:02:58 INFO - 1499716978016 Marionette DEBUG Closed connection 374
13:02:58 INFO - 1499716978016 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49798
13:02:58 INFO - 1499716978017 Marionette DEBUG Closed connection 375
13:02:58 INFO - 1499716978018 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49799
13:02:58 INFO - 1499716978019 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:02:58 INFO - 1499716978020 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978022 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978025 Marionette TRACE 376 <- [1,1,null,{"sessionId":"3f46e401-29da-491a-b4c9-f29e3eedcc8e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978026 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"}]
13:02:58 INFO - 1499716978028 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"
13:02:58 INFO - 1499716978030 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"
13:02:58 INFO - 1499716978030 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"
13:02:58 INFO - 1499716978036 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"
13:02:58 INFO - 1499716978038 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"
13:02:58 INFO - 1499716978042 Marionette TRACE 376 <- [1,2,null,{}]
13:02:58 INFO - 1499716978046 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:02:58 INFO - 1499716978048 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e4a8e1b-9df0-4d59-9977-f153ba9133db","ELEMENT":"6e4a8e1b-9df0-4d59-9977-f153ba9133db"}}]
13:02:58 INFO - 1499716978054 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6e4a8e1b-9df0-4d59-9977-f153ba9133db"}]
13:02:58 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 313ms
13:02:58 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:02:58 INFO - 1499716978288 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:02:58 INFO - 1499716978289 Marionette TRACE 376 <- [1,4,null,{}]
13:02:58 INFO - 1499716978290 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}]
13:02:58 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499716978293 Marionette TRACE 376 <- [1,5,null,{"value":null}]
13:02:58 INFO - 1499716978293 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
13:02:58 INFO - 1499716978295 Marionette TRACE 376 <- [1,6,null,{}]
13:02:58 INFO - 1499716978296 Marionette DEBUG Closed connection 376
13:02:58 INFO - 1499716978297 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49800
13:02:58 INFO - 1499716978297 Marionette DEBUG Closed connection 377
13:02:58 INFO - 1499716978298 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49801
13:02:58 INFO - 1499716978299 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:58 INFO - 1499716978299 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978301 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978304 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f703de5c-bd7e-4833-8f60-b2ee45ca886d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978305 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
13:02:58 INFO - 1499716978306 Marionette TRACE 378 <- [1,2,null,{}]
13:02:58 INFO - 1499716978307 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:02:58 INFO - 1499716978307 Marionette TRACE 378 <- [1,3,null,{}]
13:02:58 INFO - 1499716978308 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
13:02:58 INFO - 1499716978308 Marionette TRACE 378 <- [1,4,null,{}]
13:02:58 INFO - 1499716978310 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}]
13:02:58 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499716978312 Marionette TRACE 378 <- [1,5,null,{"value":null}]
13:02:58 INFO - 1499716978313 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
13:02:58 INFO - 1499716978314 Marionette TRACE 378 <- [1,6,null,{}]
13:02:58 INFO - 1499716978316 Marionette DEBUG Closed connection 378
13:02:58 INFO - 1499716978316 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49802
13:02:58 INFO - 1499716978317 Marionette DEBUG Closed connection 379
13:02:58 INFO - 1499716978318 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49803
13:02:58 INFO - 1499716978319 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:58 INFO - 1499716978319 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978321 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978324 Marionette TRACE 380 <- [1,1,null,{"sessionId":"15a438b1-53c7-4c4c-9fe7-c425c4e881a0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978325 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
13:02:58 INFO - 1499716978326 Marionette TRACE 380 <- [1,2,null,{}]
13:02:58 INFO - 1499716978327 Marionette DEBUG Closed connection 380
13:02:58 INFO - 1499716978328 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49804
13:02:58 INFO - 1499716978328 Marionette DEBUG Closed connection 381
13:02:58 INFO - 1499716978329 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49805
13:02:58 INFO - 1499716978330 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:02:58 INFO - 1499716978330 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978332 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978335 Marionette TRACE 382 <- [1,1,null,{"sessionId":"85e1e070-d608-4383-b38b-da4973c68528","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978336 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"}]
13:02:58 INFO - 1499716978338 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"
13:02:58 INFO - 1499716978339 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"
13:02:58 INFO - 1499716978340 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"
13:02:58 INFO - 1499716978345 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"
13:02:58 INFO - 1499716978348 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"
13:02:58 INFO - 1499716978354 Marionette TRACE 382 <- [1,2,null,{}]
13:02:58 INFO - 1499716978357 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:02:58 INFO - 1499716978359 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a1d5fd89-668f-4985-a93e-e88e9d94d38d","ELEMENT":"a1d5fd89-668f-4985-a93e-e88e9d94d38d"}}]
13:02:58 INFO - 1499716978360 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"a1d5fd89-668f-4985-a93e-e88e9d94d38d"}]
13:02:58 INFO - 1499716978601 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:02:58 INFO - 1499716978602 Marionette TRACE 382 <- [1,4,null,{}]
13:02:58 INFO - 1499716978604 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}]
13:02:58 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 314ms
13:02:58 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499716978606 Marionette TRACE 382 <- [1,5,null,{"value":null}]
13:02:58 INFO - 1499716978607 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
13:02:58 INFO - 1499716978608 Marionette TRACE 382 <- [1,6,null,{}]
13:02:58 INFO - TEST-START | test_click.py TestClick.test_input_file
13:02:58 INFO - 1499716978609 Marionette DEBUG Closed connection 382
13:02:58 INFO - 1499716978610 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49806
13:02:58 INFO - 1499716978611 Marionette DEBUG Closed connection 383
13:02:58 INFO - 1499716978612 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49807
13:02:58 INFO - 1499716978613 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:58 INFO - 1499716978613 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978616 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978619 Marionette TRACE 384 <- [1,1,null,{"sessionId":"1f0db34d-f9b0-47bb-81dc-9c84c1991386","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978620 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
13:02:58 INFO - 1499716978620 Marionette TRACE 384 <- [1,2,null,{}]
13:02:58 INFO - 1499716978621 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:02:58 INFO - 1499716978621 Marionette TRACE 384 <- [1,3,null,{}]
13:02:58 INFO - 1499716978622 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
13:02:58 INFO - 1499716978622 Marionette TRACE 384 <- [1,4,null,{}]
13:02:58 INFO - 1499716978624 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}]
13:02:58 INFO - TEST-START: test_click.py TestClick.test_input_file1499716978626 Marionette TRACE 384 <- [1,5,null,{"value":null}]
13:02:58 INFO - 1499716978627 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
13:02:58 INFO - 1499716978628 Marionette TRACE 384 <- [1,6,null,{}]
13:02:58 INFO - 1499716978629 Marionette DEBUG Closed connection 384
13:02:58 INFO - 1499716978630 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49808
13:02:58 INFO - 1499716978631 Marionette DEBUG Closed connection 385
13:02:58 INFO - 1499716978631 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49809
13:02:58 INFO - 1499716978632 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:58 INFO - 1499716978633 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978635 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978645 Marionette TRACE 386 <- [1,1,null,{"sessionId":"7b427021-9a4c-41f5-9a0e-255453ac6de1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978646 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
13:02:58 INFO - 1499716978647 Marionette TRACE 386 <- [1,2,null,{}]
13:02:58 INFO - 1499716978649 Marionette DEBUG Closed connection 386
13:02:58 INFO - 1499716978649 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49810
13:02:58 INFO - 1499716978650 Marionette DEBUG Closed connection 387
13:02:58 INFO - 1499716978650 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49811
13:02:58 INFO - 1499716978651 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:02:58 INFO - 1499716978652 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978654 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978656 Marionette TRACE 388 <- [1,1,null,{"sessionId":"5149ed91-f8ce-4c6f-8714-b6b5be83f8a8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978658 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:02:58 INFO - 1499716978660 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"
13:02:58 INFO - 1499716978661 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"
13:02:58 INFO - 1499716978662 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"
13:02:58 INFO - 1499716978670 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:02:58 INFO - 1499716978671 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:02:58 INFO - 1499716978676 Marionette TRACE 388 <- [1,2,null,{}]
13:02:58 INFO - 1499716978679 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:02:58 INFO - 1499716978681 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d6b3e8e2-67f8-4fcb-b891-92d8468e64e9","ELEMENT":"d6b3e8e2-67f8-4fcb-b891-92d8468e64e9"}}]
13:02:58 INFO - 1499716978682 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d6b3e8e2-67f8-4fcb-b891-92d8468e64e9"}]
13:02:58 INFO - 1499716978709 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
13:02:58 INFO - 1499716978711 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}]
13:02:58 INFO - TEST-END: test_click.py TestClick.test_input_file1499716978713 Marionette TRACE 388 <- [1,5,null,{"value":null}]
13:02:58 INFO - 1499716978714 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
13:02:58 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 106ms
13:02:58 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:02:58 INFO - 1499716978715 Marionette TRACE 388 <- [1,6,null,{}]
13:02:58 INFO - 1499716978717 Marionette DEBUG Closed connection 388
13:02:58 INFO - 1499716978717 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49812
13:02:58 INFO - 1499716978718 Marionette DEBUG Closed connection 389
13:02:58 INFO - 1499716978719 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49813
13:02:58 INFO - 1499716978720 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:58 INFO - 1499716978721 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978723 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978725 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8d67b432-d467-4aec-b58a-7c8cac6ae660","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978727 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
13:02:58 INFO - 1499716978727 Marionette TRACE 390 <- [1,2,null,{}]
13:02:58 INFO - 1499716978728 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:02:58 INFO - 1499716978728 Marionette TRACE 390 <- [1,3,null,{}]
13:02:58 INFO - 1499716978729 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
13:02:58 INFO - 1499716978729 Marionette TRACE 390 <- [1,4,null,{}]
13:02:58 INFO - 1499716978731 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}]
13:02:58 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499716978734 Marionette TRACE 390 <- [1,5,null,{"value":null}]
13:02:58 INFO - 1499716978735 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
13:02:58 INFO - 1499716978736 Marionette TRACE 390 <- [1,6,null,{}]
13:02:58 INFO - 1499716978737 Marionette DEBUG Closed connection 390
13:02:58 INFO - 1499716978738 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49814
13:02:58 INFO - 1499716978738 Marionette DEBUG Closed connection 391
13:02:58 INFO - 1499716978739 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49815
13:02:58 INFO - 1499716978740 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:58 INFO - 1499716978740 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978742 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978745 Marionette TRACE 392 <- [1,1,null,{"sessionId":"737211bc-91d3-4ddd-a524-b403b4d5b5d9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978746 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
13:02:58 INFO - 1499716978747 Marionette TRACE 392 <- [1,2,null,{}]
13:02:58 INFO - 1499716978748 Marionette DEBUG Closed connection 392
13:02:58 INFO - 1499716978748 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49816
13:02:58 INFO - 1499716978749 Marionette DEBUG Closed connection 393
13:02:58 INFO - 1499716978750 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49817
13:02:58 INFO - 1499716978751 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:02:58 INFO - 1499716978751 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:58 INFO - 1499716978753 Marionette DEBUG Register listener.js for window 4294967297
13:02:58 INFO - 1499716978756 Marionette TRACE 394 <- [1,1,null,{"sessionId":"450dfa30-12b9-45cd-b234-4c0019d99648","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:58 INFO - 1499716978757 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"}]
13:02:58 INFO - 1499716978758 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:02:58 INFO - 1499716978760 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:02:58 INFO - 1499716978760 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:02:58 INFO - 1499716978767 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"
13:02:58 INFO - 1499716978769 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"
13:02:58 INFO - 1499716978773 Marionette TRACE 394 <- [1,2,null,{}]
13:02:58 INFO - 1499716978776 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:02:58 INFO - 1499716978778 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9adfd3aa-a85f-42d9-bf51-430e0fb3bcbc","ELEMENT":"9adfd3aa-a85f-42d9-bf51-430e0fb3bcbc"}}]
13:02:58 INFO - 1499716978779 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:02:58 INFO - 1499716978780 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b7acb391-3a39-4882-98a8-a45e82dc1859","ELEMENT":"b7acb391-3a39-4882-98a8-a45e82dc1859"}}]
13:02:58 INFO - 1499716978781 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9adfd3aa-a85f-42d9-bf51-430e0fb3bcbc"}]
13:02:59 INFO - 1499716979034 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:02:59 INFO - 1499716979035 Marionette TRACE 394 <- [1,5,null,{}]
13:02:59 INFO - 1499716979036 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b7acb391-3a39-4882-98a8-a45e82dc1859"}]
13:02:59 INFO - 1499716979050 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
13:02:59 INFO - 1499716979052 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:02:59 INFO - 1499716979054 Marionette TRACE 394 <- [1,7,null,{"value":false}]
13:02:59 INFO - 1499716979055 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}]
13:02:59 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499716979057 Marionette TRACE 394 <- [1,8,null,{"value":null}]
13:02:59 INFO - 1499716979058 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
13:02:59 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 343ms
13:02:59 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:02:59 INFO - 1499716979059 Marionette TRACE 394 <- [1,9,null,{}]
13:02:59 INFO - 1499716979061 Marionette DEBUG Closed connection 394
13:02:59 INFO - 1499716979062 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49818
13:02:59 INFO - 1499716979062 Marionette DEBUG Closed connection 395
13:02:59 INFO - 1499716979063 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49819
13:02:59 INFO - 1499716979064 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:59 INFO - 1499716979064 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:59 INFO - 1499716979067 Marionette DEBUG Register listener.js for window 4294967297
13:02:59 INFO - 1499716979070 Marionette TRACE 396 <- [1,1,null,{"sessionId":"59b82887-9acc-4c98-b97a-0a3c492521bb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:59 INFO - 1499716979071 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
13:02:59 INFO - 1499716979071 Marionette TRACE 396 <- [1,2,null,{}]
13:02:59 INFO - 1499716979072 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:02:59 INFO - 1499716979072 Marionette TRACE 396 <- [1,3,null,{}]
13:02:59 INFO - 1499716979073 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
13:02:59 INFO - 1499716979074 Marionette TRACE 396 <- [1,4,null,{}]
13:02:59 INFO - 1499716979075 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}]
13:02:59 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499716979077 Marionette TRACE 396 <- [1,5,null,{"value":null}]
13:02:59 INFO - 1499716979078 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
13:02:59 INFO - 1499716979080 Marionette TRACE 396 <- [1,6,null,{}]
13:02:59 INFO - 1499716979081 Marionette DEBUG Closed connection 396
13:02:59 INFO - 1499716979081 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49820
13:02:59 INFO - 1499716979082 Marionette DEBUG Closed connection 397
13:02:59 INFO - 1499716979083 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49821
13:02:59 INFO - 1499716979083 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:02:59 INFO - 1499716979084 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:59 INFO - 1499716979086 Marionette DEBUG Register listener.js for window 4294967297
13:02:59 INFO - 1499716979089 Marionette TRACE 398 <- [1,1,null,{"sessionId":"4b151bdf-879c-43a6-82db-37a70ed33759","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:59 INFO - 1499716979090 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
13:02:59 INFO - 1499716979091 Marionette TRACE 398 <- [1,2,null,{}]
13:02:59 INFO - 1499716979092 Marionette DEBUG Closed connection 398
13:02:59 INFO - 1499716979093 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49822
13:02:59 INFO - 1499716979093 Marionette DEBUG Closed connection 399
13:02:59 INFO - 1499716979094 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49823
13:02:59 INFO - 1499716979095 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:02:59 INFO - 1499716979095 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}
13:02:59 INFO - 1499716979097 Marionette DEBUG Register listener.js for window 4294967297
13:02:59 INFO - 1499716979100 Marionette TRACE 400 <- [1,1,null,{"sessionId":"23df5c01-43ea-4ab1-92b0-afd122df179b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-285.000\\AppData\\Local\\Temp\\tmpwkqvet.mozrunner","moz:accessibilityChecks":false}}]
13:02:59 INFO - 1499716979101 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"}]
13:02:59 INFO - 1499716979103 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"
13:02:59 INFO - 1499716979104 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"
13:02:59 INFO - 1499716979105 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"
13:02:59 INFO - 1499716979110 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"
13:02:59 INFO - 1499716979113 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"
13:02:59 INFO - 1499716979115 Marionette TRACE 400 <- [1,2,null,{}]
13:02:59 INFO - 1499716979122 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:02:59 INFO - 1499716979125 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e41d7d8c-57c9-452b-a84d-7ef4806a7012","ELEMENT":"e41d7d8c-57c9-452b-a84d-7ef4806a7012"}}]
13:02:59 INFO - 1499716979126 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e41d7d8c-57c9-452b-a84d-7ef4806a7012"}]
13:02:59 INFO - 1499716979149 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
13:02:59 INFO - 1499716979150 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e41d7d8c-57c9-452b-a84d-7ef4806a7012"}]
13:02:59 INFO - 1499716979163 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element