could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:18:34 INFO - 1501071514208 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:18:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501071514211 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:18:34 INFO - 1501071514212 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:18:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 127ms
05:18:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:18:34 INFO - 1501071514213 Marionette TRACE 352 <- [1,6,null,{}]
05:18:34 INFO - 1501071514215 Marionette DEBUG Closed connection 352
05:18:34 INFO - 1501071514216 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49752
05:18:34 INFO - 1501071514216 Marionette DEBUG Closed connection 353
05:18:34 INFO - 1501071514217 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49753
05:18:34 INFO - 1501071514218 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:34 INFO - 1501071514218 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514220 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514223 Marionette TRACE 354 <- [1,1,null,{"sessionId":"04852d71-caa4-46cf-9e9e-20419c35af32","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514225 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:18:34 INFO - 1501071514226 Marionette TRACE 354 <- [1,2,null,{}]
05:18:34 INFO - 1501071514228 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:18:34 INFO - 1501071514229 Marionette TRACE 354 <- [1,3,null,{}]
05:18:34 INFO - 1501071514234 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:18:34 INFO - 1501071514235 Marionette TRACE 354 <- [1,4,null,{}]
05:18:34 INFO - 1501071514236 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:18:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501071514238 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:18:34 INFO - 1501071514239 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:18:34 INFO - 1501071514240 Marionette TRACE 354 <- [1,6,null,{}]
05:18:34 INFO - 1501071514242 Marionette DEBUG Closed connection 354
05:18:34 INFO - 1501071514243 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49754
05:18:34 INFO - 1501071514244 Marionette DEBUG Closed connection 355
05:18:34 INFO - 1501071514244 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49755
05:18:34 INFO - 1501071514245 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:34 INFO - 1501071514246 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514247 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514250 Marionette TRACE 356 <- [1,1,null,{"sessionId":"8c173232-a550-4d7c-8ce6-e51757d9a8e4","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514252 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:18:34 INFO - 1501071514253 Marionette TRACE 356 <- [1,2,null,{}]
05:18:34 INFO - 1501071514255 Marionette DEBUG Closed connection 356
05:18:34 INFO - 1501071514255 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49756
05:18:34 INFO - 1501071514256 Marionette DEBUG Closed connection 357
05:18:34 INFO - 1501071514256 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49757
05:18:34 INFO - 1501071514257 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:18:34 INFO - 1501071514258 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514259 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514262 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a50f0b4d-227c-4ff9-90db-ec53247ee7a4","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514264 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49328/clicks.html"}]
05:18:34 INFO - 1501071514265 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/hidden.html"
05:18:34 INFO - 1501071514269 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/hidden.html"
05:18:34 INFO - 1501071514269 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/hidden.html"
05:18:34 INFO - 1501071514284 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49328/clicks.html"
05:18:34 INFO - 1501071514288 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49328/clicks.html"
05:18:34 INFO - 1501071514296 Marionette TRACE 358 <- [1,2,null,{}]
05:18:34 INFO - 1501071514301 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:18:34 INFO - 1501071514304 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c45c5f4a-99c7-4adb-bdf1-e0533c7a7f0d","ELEMENT":"c45c5f4a-99c7-4adb-bdf1-e0533c7a7f0d"}}]
05:18:34 INFO - 1501071514307 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"c45c5f4a-99c7-4adb-bdf1-e0533c7a7f0d"}]
05:18:34 INFO - 1501071514349 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/clicks.html"
05:18:34 INFO - 1501071514357 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/clicks.html"
05:18:34 INFO - 1501071514357 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/clicks.html"
05:18:34 INFO - 1501071514380 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49328/test.html"
05:18:34 INFO - 1501071514381 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49328/test.html"
05:18:34 INFO - 1501071514384 Marionette TRACE 358 <- [1,4,null,{}]
05:18:34 INFO - 1501071514385 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:18:34 INFO - 1501071514393 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f4ba07e2-0202-42a6-b1f5-bffdc3eeb172","ELEMENT":"f4ba07e2-0202-42a6-b1f5-bffdc3eeb172"}}]
05:18:34 INFO - 1501071514395 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:18:34 INFO - 1501071514395 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:18:34 INFO - 1501071514400 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:18:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501071514403 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:18:34 INFO - 1501071514404 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:18:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 193ms
05:18:34 INFO - TEST-START | test_click.py TestClick.test_container_element
05:18:34 INFO - 1501071514406 Marionette TRACE 358 <- [1,8,null,{}]
05:18:34 INFO - 1501071514410 Marionette DEBUG Closed connection 358
05:18:34 INFO - 1501071514410 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49760
05:18:34 INFO - 1501071514411 Marionette DEBUG Closed connection 359
05:18:34 INFO - 1501071514411 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49761
05:18:34 INFO - 1501071514412 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:34 INFO - 1501071514413 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514415 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514417 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3fb63ccd-912c-41bb-a0b5-8f1640c3c7fb","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514419 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:18:34 INFO - 1501071514420 Marionette TRACE 360 <- [1,2,null,{}]
05:18:34 INFO - 1501071514421 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:18:34 INFO - 1501071514421 Marionette TRACE 360 <- [1,3,null,{}]
05:18:34 INFO - 1501071514422 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:18:34 INFO - 1501071514422 Marionette TRACE 360 <- [1,4,null,{}]
05:18:34 INFO - 1501071514424 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:18:34 INFO - TEST-START: test_click.py TestClick.test_container_element1501071514426 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:18:34 INFO - 1501071514427 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:18:34 INFO - 1501071514428 Marionette TRACE 360 <- [1,6,null,{}]
05:18:34 INFO - 1501071514429 Marionette DEBUG Closed connection 360
05:18:34 INFO - 1501071514430 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49762
05:18:34 INFO - 1501071514430 Marionette DEBUG Closed connection 361
05:18:34 INFO - 1501071514431 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49763
05:18:34 INFO - 1501071514432 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:34 INFO - 1501071514432 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514434 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514437 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ccda8b3e-fab0-4eae-94e7-de8243a59ca1","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514439 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:18:34 INFO - 1501071514440 Marionette TRACE 362 <- [1,2,null,{}]
05:18:34 INFO - 1501071514442 Marionette DEBUG Closed connection 362
05:18:34 INFO - 1501071514442 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49764
05:18:34 INFO - 1501071514443 Marionette DEBUG Closed connection 363
05:18:34 INFO - 1501071514444 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49765
05:18:34 INFO - 1501071514444 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:18:34 INFO - 1501071514445 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514446 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514449 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1bd255d9-9ffb-4db9-81b8-5d8c9993bc8e","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514451 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:18:34 INFO - 1501071514452 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/test.html"
05:18:34 INFO - 1501071514453 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/test.html"
05:18:34 INFO - 1501071514454 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/test.html"
05:18:34 INFO - 1501071514466 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:18:34 INFO - 1501071514470 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:18:34 INFO - 1501071514478 Marionette TRACE 364 <- [1,2,null,{}]
05:18:34 INFO - 1501071514480 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:18:34 INFO - 1501071514482 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f2e4bb8f-e853-4e9e-a846-f3a6ee1bbb0e","ELEMENT":"f2e4bb8f-e853-4e9e-a846-f3a6ee1bbb0e"}}]
05:18:34 INFO - 1501071514483 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"f2e4bb8f-e853-4e9e-a846-f3a6ee1bbb0e"}]
05:18:34 INFO - 1501071514722 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:18:34 INFO - 1501071514722 Marionette TRACE 364 <- [1,4,null,{}]
05:18:34 INFO - 1501071514723 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"f2e4bb8f-e853-4e9e-a846-f3a6ee1bbb0e","name":"selected"}]
05:18:34 INFO - 1501071514727 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:18:34 INFO - 1501071514729 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:18:34 INFO - TEST-END: test_click.py TestClick.test_container_element1501071514731 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:18:34 INFO - 1501071514732 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:18:34 INFO - 1501071514733 Marionette TRACE 364 <- [1,7,null,{}]
05:18:34 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 327ms
05:18:34 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:18:34 INFO - 1501071514735 Marionette DEBUG Closed connection 364
05:18:34 INFO - 1501071514737 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49766
05:18:34 INFO - 1501071514738 Marionette DEBUG Closed connection 365
05:18:34 INFO - 1501071514738 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49767
05:18:34 INFO - 1501071514739 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:34 INFO - 1501071514739 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514741 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514743 Marionette TRACE 366 <- [1,1,null,{"sessionId":"389c8280-ffcf-4c16-a468-2d5904a5b02f","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514751 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:18:34 INFO - 1501071514752 Marionette TRACE 366 <- [1,2,null,{}]
05:18:34 INFO - 1501071514753 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:18:34 INFO - 1501071514753 Marionette TRACE 366 <- [1,3,null,{}]
05:18:34 INFO - 1501071514754 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:18:34 INFO - 1501071514755 Marionette TRACE 366 <- [1,4,null,{}]
05:18:34 INFO - 1501071514757 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:18:34 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501071514759 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:18:34 INFO - 1501071514760 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:18:34 INFO - 1501071514761 Marionette TRACE 366 <- [1,6,null,{}]
05:18:34 INFO - 1501071514763 Marionette DEBUG Closed connection 366
05:18:34 INFO - 1501071514763 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49768
05:18:34 INFO - 1501071514764 Marionette DEBUG Closed connection 367
05:18:34 INFO - 1501071514764 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49769
05:18:34 INFO - 1501071514765 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:34 INFO - 1501071514766 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514767 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514770 Marionette TRACE 368 <- [1,1,null,{"sessionId":"0ce25f73-d5ab-40bd-9186-d919e689fdc7","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514772 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:18:34 INFO - 1501071514773 Marionette TRACE 368 <- [1,2,null,{}]
05:18:34 INFO - 1501071514775 Marionette DEBUG Closed connection 368
05:18:34 INFO - 1501071514775 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49770
05:18:34 INFO - 1501071514776 Marionette DEBUG Closed connection 369
05:18:34 INFO - 1501071514777 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49771
05:18:34 INFO - 1501071514778 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:18:34 INFO - 1501071514778 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:34 INFO - 1501071514780 Marionette DEBUG Register listener.js for window 14
05:18:34 INFO - 1501071514782 Marionette TRACE 370 <- [1,1,null,{"sessionId":"42306266-595d-492c-8a75-43aeeea2180c","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:34 INFO - 1501071514784 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:18:34 INFO - 1501071514785 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:18:34 INFO - 1501071514787 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:18:34 INFO - 1501071514787 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:18:34 INFO - 1501071514796 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:18:34 INFO - 1501071514799 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:18:34 INFO - 1501071514802 Marionette TRACE 370 <- [1,2,null,{}]
05:18:34 INFO - 1501071514809 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:18:34 INFO - 1501071514811 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5148e229-ecc1-4594-b349-81fb760de901","ELEMENT":"5148e229-ecc1-4594-b349-81fb760de901"}}]
05:18:34 INFO - 1501071514813 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"5148e229-ecc1-4594-b349-81fb760de901"}]
05:18:35 INFO - 1501071515052 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:18:35 INFO - 1501071515052 Marionette TRACE 370 <- [1,4,null,{}]
05:18:35 INFO - 1501071515053 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"5148e229-ecc1-4594-b349-81fb760de901","name":"selected"}]
05:18:35 INFO - 1501071515057 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:18:35 INFO - 1501071515059 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:18:35 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501071515061 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:18:35 INFO - 1501071515062 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:18:35 INFO - 1501071515063 Marionette TRACE 370 <- [1,7,null,{}]
05:18:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 329ms
05:18:35 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:18:35 INFO - 1501071515064 Marionette DEBUG Closed connection 370
05:18:35 INFO - 1501071515066 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49772
05:18:35 INFO - 1501071515067 Marionette DEBUG Closed connection 371
05:18:35 INFO - 1501071515067 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49773
05:18:35 INFO - 1501071515068 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:35 INFO - 1501071515069 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515070 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515073 Marionette TRACE 372 <- [1,1,null,{"sessionId":"03d79a20-34f2-4a6a-befa-f7c0b952093d","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515075 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:18:35 INFO - 1501071515075 Marionette TRACE 372 <- [1,2,null,{}]
05:18:35 INFO - 1501071515076 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:18:35 INFO - 1501071515076 Marionette TRACE 372 <- [1,3,null,{}]
05:18:35 INFO - 1501071515077 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:18:35 INFO - 1501071515077 Marionette TRACE 372 <- [1,4,null,{}]
05:18:35 INFO - 1501071515079 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:18:35 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501071515081 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:18:35 INFO - 1501071515082 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:18:35 INFO - 1501071515083 Marionette TRACE 372 <- [1,6,null,{}]
05:18:35 INFO - 1501071515084 Marionette DEBUG Closed connection 372
05:18:35 INFO - 1501071515085 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49774
05:18:35 INFO - 1501071515085 Marionette DEBUG Closed connection 373
05:18:35 INFO - 1501071515086 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49775
05:18:35 INFO - 1501071515087 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:35 INFO - 1501071515087 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515089 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515091 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c33fb76a-22a9-46d9-a921-56d9845cb054","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515093 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:18:35 INFO - 1501071515094 Marionette TRACE 374 <- [1,2,null,{}]
05:18:35 INFO - 1501071515095 Marionette DEBUG Closed connection 374
05:18:35 INFO - 1501071515096 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49776
05:18:35 INFO - 1501071515096 Marionette DEBUG Closed connection 375
05:18:35 INFO - 1501071515097 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49777
05:18:35 INFO - 1501071515098 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:18:35 INFO - 1501071515098 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515100 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515102 Marionette TRACE 376 <- [1,1,null,{"sessionId":"4f39e9db-5857-4e6a-96c2-4e353167dad4","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515104 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:18:35 INFO - 1501071515105 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:18:35 INFO - 1501071515107 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:18:35 INFO - 1501071515107 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:18:35 INFO - 1501071515117 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:18:35 INFO - 1501071515120 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:18:35 INFO - 1501071515128 Marionette TRACE 376 <- [1,2,null,{}]
05:18:35 INFO - 1501071515131 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:18:35 INFO - 1501071515133 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5f0df5f6-06dd-4c85-a3dd-ce775e66c5ce","ELEMENT":"5f0df5f6-06dd-4c85-a3dd-ce775e66c5ce"}}]
05:18:35 INFO - 1501071515134 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"5f0df5f6-06dd-4c85-a3dd-ce775e66c5ce"}]
05:18:35 INFO - 1501071515375 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:18:35 INFO - 1501071515375 Marionette TRACE 376 <- [1,4,null,{}]
05:18:35 INFO - 1501071515377 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:18:35 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501071515379 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:18:35 INFO - 1501071515380 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:18:35 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 317ms
05:18:35 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:18:35 INFO - 1501071515381 Marionette TRACE 376 <- [1,6,null,{}]
05:18:35 INFO - 1501071515383 Marionette DEBUG Closed connection 376
05:18:35 INFO - 1501071515384 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49778
05:18:35 INFO - 1501071515384 Marionette DEBUG Closed connection 377
05:18:35 INFO - 1501071515385 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49779
05:18:35 INFO - 1501071515386 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:35 INFO - 1501071515386 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515388 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515390 Marionette TRACE 378 <- [1,1,null,{"sessionId":"5e5229d6-b6f8-49dc-a291-4931649f3d52","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515392 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:18:35 INFO - 1501071515393 Marionette TRACE 378 <- [1,2,null,{}]
05:18:35 INFO - 1501071515393 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:18:35 INFO - 1501071515394 Marionette TRACE 378 <- [1,3,null,{}]
05:18:35 INFO - 1501071515395 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:18:35 INFO - 1501071515395 Marionette TRACE 378 <- [1,4,null,{}]
05:18:35 INFO - 1501071515397 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:18:35 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501071515398 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:18:35 INFO - 1501071515399 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:18:35 INFO - 1501071515400 Marionette TRACE 378 <- [1,6,null,{}]
05:18:35 INFO - 1501071515402 Marionette DEBUG Closed connection 378
05:18:35 INFO - 1501071515402 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49780
05:18:35 INFO - 1501071515403 Marionette DEBUG Closed connection 379
05:18:35 INFO - 1501071515404 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49781
05:18:35 INFO - 1501071515404 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:35 INFO - 1501071515405 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515407 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515409 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a4895fa4-101d-4d04-a3da-d824c5d90e94","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515411 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:18:35 INFO - 1501071515412 Marionette TRACE 380 <- [1,2,null,{}]
05:18:35 INFO - 1501071515414 Marionette DEBUG Closed connection 380
05:18:35 INFO - 1501071515414 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49782
05:18:35 INFO - 1501071515414 Marionette DEBUG Closed connection 381
05:18:35 INFO - 1501071515415 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49783
05:18:35 INFO - 1501071515416 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:18:35 INFO - 1501071515416 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515418 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515420 Marionette TRACE 382 <- [1,1,null,{"sessionId":"4ea02fe7-6bbe-4e0e-8797-48e325486514","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515422 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:18:35 INFO - 1501071515423 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:18:35 INFO - 1501071515425 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:18:35 INFO - 1501071515425 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:18:35 INFO - 1501071515434 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:18:35 INFO - 1501071515442 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:18:35 INFO - 1501071515445 Marionette TRACE 382 <- [1,2,null,{}]
05:18:35 INFO - 1501071515447 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:18:35 INFO - 1501071515448 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05ea3d6c-d596-4838-80ee-6ca71e87da44","ELEMENT":"05ea3d6c-d596-4838-80ee-6ca71e87da44"}}]
05:18:35 INFO - 1501071515449 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"05ea3d6c-d596-4838-80ee-6ca71e87da44"}]
05:18:35 INFO - 1501071515691 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:18:35 INFO - 1501071515691 Marionette TRACE 382 <- [1,4,null,{}]
05:18:35 INFO - 1501071515693 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:18:35 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501071515695 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:18:35 INFO - 1501071515696 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:18:35 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 316ms
05:18:35 INFO - 1501071515697 Marionette TRACE 382 <- [1,6,null,{}]
05:18:35 INFO - TEST-START | test_click.py TestClick.test_input_file
05:18:35 INFO - 1501071515699 Marionette DEBUG Closed connection 382
05:18:35 INFO - 1501071515700 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49784
05:18:35 INFO - 1501071515700 Marionette DEBUG Closed connection 383
05:18:35 INFO - 1501071515701 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49785
05:18:35 INFO - 1501071515702 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:35 INFO - 1501071515703 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515704 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515707 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b0a9d0f4-214d-4c8b-9e3a-bf1c9b375b81","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515708 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:18:35 INFO - 1501071515709 Marionette TRACE 384 <- [1,2,null,{}]
05:18:35 INFO - 1501071515709 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:18:35 INFO - 1501071515710 Marionette TRACE 384 <- [1,3,null,{}]
05:18:35 INFO - 1501071515710 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:18:35 INFO - 1501071515711 Marionette TRACE 384 <- [1,4,null,{}]
05:18:35 INFO - 1501071515712 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:18:35 INFO - TEST-START: test_click.py TestClick.test_input_file1501071515714 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:18:35 INFO - 1501071515715 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:18:35 INFO - 1501071515716 Marionette TRACE 384 <- [1,6,null,{}]
05:18:35 INFO - 1501071515717 Marionette DEBUG Closed connection 384
05:18:35 INFO - 1501071515718 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49786
05:18:35 INFO - 1501071515718 Marionette DEBUG Closed connection 385
05:18:35 INFO - 1501071515719 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49787
05:18:35 INFO - 1501071515720 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:35 INFO - 1501071515720 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515722 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515730 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b3b3cd06-9aef-48bc-afc3-673f416e99df","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515733 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:18:35 INFO - 1501071515734 Marionette TRACE 386 <- [1,2,null,{}]
05:18:35 INFO - 1501071515736 Marionette DEBUG Closed connection 386
05:18:35 INFO - 1501071515736 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49788
05:18:35 INFO - 1501071515737 Marionette DEBUG Closed connection 387
05:18:35 INFO - 1501071515738 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49789
05:18:35 INFO - 1501071515738 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:18:35 INFO - 1501071515739 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515741 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515743 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2263cfef-edcc-401b-bd4f-e9fda26b2d3c","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515745 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:18:35 INFO - 1501071515746 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:18:35 INFO - 1501071515747 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:18:35 INFO - 1501071515748 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:18:35 INFO - 1501071515765 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:18:35 INFO - 1501071515767 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:18:35 INFO - 1501071515770 Marionette TRACE 388 <- [1,2,null,{}]
05:18:35 INFO - 1501071515776 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:18:35 INFO - 1501071515778 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"455cf787-2db4-4b6a-80a1-7ce36464fe11","ELEMENT":"455cf787-2db4-4b6a-80a1-7ce36464fe11"}}]
05:18:35 INFO - 1501071515779 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"455cf787-2db4-4b6a-80a1-7ce36464fe11"}]
05:18:35 INFO - 1501071515804 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:18:35 INFO - 1501071515807 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:18:35 INFO - TEST-END: test_click.py TestClick.test_input_file1501071515809 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:18:35 INFO - 1501071515818 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:18:35 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 122ms
05:18:35 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:18:35 INFO - 1501071515820 Marionette TRACE 388 <- [1,6,null,{}]
05:18:35 INFO - 1501071515821 Marionette DEBUG Closed connection 388
05:18:35 INFO - 1501071515822 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49790
05:18:35 INFO - 1501071515823 Marionette DEBUG Closed connection 389
05:18:35 INFO - 1501071515824 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49791
05:18:35 INFO - 1501071515825 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:35 INFO - 1501071515825 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515827 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515829 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9312a118-2b50-4e62-af17-a790fa9e350e","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515834 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:18:35 INFO - 1501071515835 Marionette TRACE 390 <- [1,2,null,{}]
05:18:35 INFO - 1501071515835 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:18:35 INFO - 1501071515836 Marionette TRACE 390 <- [1,3,null,{}]
05:18:35 INFO - 1501071515837 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:18:35 INFO - 1501071515837 Marionette TRACE 390 <- [1,4,null,{}]
05:18:35 INFO - 1501071515838 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:18:35 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501071515840 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:18:35 INFO - 1501071515841 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:18:35 INFO - 1501071515842 Marionette TRACE 390 <- [1,6,null,{}]
05:18:35 INFO - 1501071515844 Marionette DEBUG Closed connection 390
05:18:35 INFO - 1501071515845 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49792
05:18:35 INFO - 1501071515845 Marionette DEBUG Closed connection 391
05:18:35 INFO - 1501071515846 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49793
05:18:35 INFO - 1501071515847 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:35 INFO - 1501071515847 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515849 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515851 Marionette TRACE 392 <- [1,1,null,{"sessionId":"82e753b4-2359-4fc6-8db4-b42f4bfbf096","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515853 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:18:35 INFO - 1501071515854 Marionette TRACE 392 <- [1,2,null,{}]
05:18:35 INFO - 1501071515856 Marionette DEBUG Closed connection 392
05:18:35 INFO - 1501071515856 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49794
05:18:35 INFO - 1501071515857 Marionette DEBUG Closed connection 393
05:18:35 INFO - 1501071515857 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49795
05:18:35 INFO - 1501071515858 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:18:35 INFO - 1501071515859 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:35 INFO - 1501071515860 Marionette DEBUG Register listener.js for window 14
05:18:35 INFO - 1501071515863 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3f6b2322-2ea5-486f-b80a-b0287d277f13","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:35 INFO - 1501071515864 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:18:35 INFO - 1501071515866 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:18:35 INFO - 1501071515867 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:18:35 INFO - 1501071515867 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:18:35 INFO - 1501071515883 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:18:35 INFO - 1501071515885 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:18:35 INFO - 1501071515888 Marionette TRACE 394 <- [1,2,null,{}]
05:18:35 INFO - 1501071515898 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:18:35 INFO - 1501071515900 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b0d7a99b-83c8-4e53-9956-36bda23606f8","ELEMENT":"b0d7a99b-83c8-4e53-9956-36bda23606f8"}}]
05:18:35 INFO - 1501071515901 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:18:35 INFO - 1501071515902 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b3ed380f-337f-4aa1-b1dc-b335aecefb63","ELEMENT":"b3ed380f-337f-4aa1-b1dc-b335aecefb63"}}]
05:18:35 INFO - 1501071515904 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b0d7a99b-83c8-4e53-9956-36bda23606f8"}]
05:18:36 INFO - 1501071516144 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:18:36 INFO - 1501071516144 Marionette TRACE 394 <- [1,5,null,{}]
05:18:36 INFO - 1501071516145 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b3ed380f-337f-4aa1-b1dc-b335aecefb63"}]
05:18:36 INFO - 1501071516160 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:18:36 INFO - 1501071516162 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:18:36 INFO - 1501071516164 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:18:36 INFO - 1501071516165 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:18:36 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501071516167 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:18:36 INFO - 1501071516168 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:18:36 INFO - 1501071516169 Marionette TRACE 394 <- [1,9,null,{}]
05:18:36 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 349ms
05:18:36 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:18:36 INFO - 1501071516171 Marionette DEBUG Closed connection 394
05:18:36 INFO - 1501071516172 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49796
05:18:36 INFO - 1501071516173 Marionette DEBUG Closed connection 395
05:18:36 INFO - 1501071516173 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49797
05:18:36 INFO - 1501071516174 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:36 INFO - 1501071516174 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:36 INFO - 1501071516176 Marionette DEBUG Register listener.js for window 14
05:18:36 INFO - 1501071516179 Marionette TRACE 396 <- [1,1,null,{"sessionId":"68da9a72-1517-4cb3-ba8a-05ba906a1519","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:36 INFO - 1501071516180 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:18:36 INFO - 1501071516181 Marionette TRACE 396 <- [1,2,null,{}]
05:18:36 INFO - 1501071516181 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:18:36 INFO - 1501071516182 Marionette TRACE 396 <- [1,3,null,{}]
05:18:36 INFO - 1501071516183 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:18:36 INFO - 1501071516183 Marionette TRACE 396 <- [1,4,null,{}]
05:18:36 INFO - 1501071516185 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:18:36 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501071516187 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:18:36 INFO - 1501071516188 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:18:36 INFO - 1501071516189 Marionette TRACE 396 <- [1,6,null,{}]
05:18:36 INFO - 1501071516190 Marionette DEBUG Closed connection 396
05:18:36 INFO - 1501071516191 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49798
05:18:36 INFO - 1501071516191 Marionette DEBUG Closed connection 397
05:18:36 INFO - 1501071516192 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49799
05:18:36 INFO - 1501071516193 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:18:36 INFO - 1501071516193 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:36 INFO - 1501071516195 Marionette DEBUG Register listener.js for window 14
05:18:36 INFO - 1501071516198 Marionette TRACE 398 <- [1,1,null,{"sessionId":"da2a962f-c59d-423a-b7db-7d5403548f8a","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:36 INFO - 1501071516200 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:18:36 INFO - 1501071516201 Marionette TRACE 398 <- [1,2,null,{}]
05:18:36 INFO - 1501071516202 Marionette DEBUG Closed connection 398
05:18:36 INFO - 1501071516203 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49800
05:18:36 INFO - 1501071516204 Marionette DEBUG Closed connection 399
05:18:36 INFO - 1501071516204 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49801
05:18:36 INFO - 1501071516205 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:18:36 INFO - 1501071516205 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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}
05:18:36 INFO - 1501071516207 Marionette DEBUG Register listener.js for window 14
05:18:36 INFO - 1501071516210 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d7c32fb4-282b-484a-aff0-efe047d6db91","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":3052,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-297.001\\AppData\\Local\\Temp\\tmpcd0oh0.mozrunner","moz:accessibilityChecks":false}}]
05:18:36 INFO - 1501071516211 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:18:36 INFO - 1501071516212 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:18:36 INFO - 1501071516214 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:18:36 INFO - 1501071516214 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:18:36 INFO - 1501071516231 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:18:36 INFO - 1501071516233 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:18:36 INFO - 1501071516236 Marionette TRACE 400 <- [1,2,null,{}]
05:18:36 INFO - 1501071516242 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:18:36 INFO - 1501071516244 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1c56cb37-d4fa-4986-b023-0a1a354e07ed","ELEMENT":"1c56cb37-d4fa-4986-b023-0a1a354e07ed"}}]
05:18:36 INFO - 1501071516245 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1c56cb37-d4fa-4986-b023-0a1a354e07ed"}]
05:18:36 INFO - 1501071516266 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:18:36 INFO - 1501071516267 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"1c56cb37-d4fa-4986-b023-0a1a354e07ed"}]
05:18:36 INFO - 1501071516283 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element