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]
21:43:44 INFO - 1500957824657 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}]
21:43:44 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500957824660 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:43:44 INFO - 1500957824661 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:43:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 124ms
21:43:44 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:43:44 INFO - 1500957824663 Marionette TRACE 352 <- [1,6,null,{}]
21:43:44 INFO - 1500957824664 Marionette DEBUG Closed connection 352
21:43:44 INFO - 1500957824665 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49984
21:43:44 INFO - 1500957824666 Marionette DEBUG Closed connection 353
21:43:44 INFO - 1500957824667 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49985
21:43:44 INFO - 1500957824668 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:44 INFO - 1500957824668 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:44 INFO - 1500957824670 Marionette DEBUG Register listener.js for window 4294967297
21:43:44 INFO - 1500957824673 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f8e34a18-4008-4d10-9347-c10f0da00883","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:44 INFO - 1500957824674 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:43:44 INFO - 1500957824675 Marionette TRACE 354 <- [1,2,null,{}]
21:43:44 INFO - 1500957824676 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:44 INFO - 1500957824676 Marionette TRACE 354 <- [1,3,null,{}]
21:43:44 INFO - 1500957824677 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:44 INFO - 1500957824677 Marionette TRACE 354 <- [1,4,null,{}]
21:43:44 INFO - 1500957824679 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}]
21:43:44 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500957824682 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:43:44 INFO - 1500957824683 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:43:44 INFO - 1500957824684 Marionette TRACE 354 <- [1,6,null,{}]
21:43:44 INFO - 1500957824685 Marionette DEBUG Closed connection 354
21:43:44 INFO - 1500957824686 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49986
21:43:44 INFO - 1500957824686 Marionette DEBUG Closed connection 355
21:43:44 INFO - 1500957824687 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49987
21:43:44 INFO - 1500957824688 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:44 INFO - 1500957824689 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:44 INFO - 1500957824691 Marionette DEBUG Register listener.js for window 4294967297
21:43:44 INFO - 1500957824694 Marionette TRACE 356 <- [1,1,null,{"sessionId":"12564b5a-03f5-43ed-992b-02f26c688d32","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:44 INFO - 1500957824695 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:43:44 INFO - 1500957824696 Marionette TRACE 356 <- [1,2,null,{}]
21:43:44 INFO - 1500957824697 Marionette DEBUG Closed connection 356
21:43:44 INFO - 1500957824698 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49988
21:43:44 INFO - 1500957824699 Marionette DEBUG Closed connection 357
21:43:44 INFO - 1500957824699 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49989
21:43:44 INFO - 1500957824700 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:44 INFO - 1500957824701 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:44 INFO - 1500957824708 Marionette DEBUG Register listener.js for window 4294967297
21:43:44 INFO - 1500957824711 Marionette TRACE 358 <- [1,1,null,{"sessionId":"febb4004-3296-49f2-b37b-d96b0ce4102b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:44 INFO - 1500957824712 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49546/clicks.html"}]
21:43:44 INFO - 1500957824714 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49546/hidden.html"
21:43:44 INFO - 1500957824722 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49546/hidden.html"
21:43:44 INFO - 1500957824723 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49546/hidden.html"
21:43:44 INFO - 1500957824730 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49546/clicks.html"
21:43:44 INFO - 1500957824734 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49546/clicks.html"
21:43:44 INFO - 1500957824735 Marionette TRACE 358 <- [1,2,null,{}]
21:43:44 INFO - 1500957824742 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:43:44 INFO - 1500957824744 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0dcfa64a-ba34-4f4a-855d-d3ab7640913c","ELEMENT":"0dcfa64a-ba34-4f4a-855d-d3ab7640913c"}}]
21:43:44 INFO - 1500957824746 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0dcfa64a-ba34-4f4a-855d-d3ab7640913c"}]
21:43:44 INFO - 1500957824782 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49546/clicks.html"
21:43:44 INFO - 1500957824789 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49546/clicks.html"
21:43:44 INFO - 1500957824789 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49546/clicks.html"
21:43:44 INFO - 1500957824796 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49546/test.html"
21:43:44 INFO - 1500957824799 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49546/test.html"
21:43:44 INFO - 1500957824801 Marionette TRACE 358 <- [1,4,null,{}]
21:43:44 INFO - 1500957824809 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:43:44 INFO - 1500957824814 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d1094b8-a0b4-41ff-ae5f-6a25d8eaf6e7","ELEMENT":"8d1094b8-a0b4-41ff-ae5f-6a25d8eaf6e7"}}]
21:43:44 INFO - 1500957824815 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:43:44 INFO - 1500957824815 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:43:44 INFO - 1500957824817 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}]
21:43:44 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500957824820 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:43:44 INFO - 1500957824820 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:43:44 INFO - 1500957824822 Marionette TRACE 358 <- [1,8,null,{}]
21:43:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 158ms
21:43:44 INFO - TEST-START | test_click.py TestClick.test_container_element
21:43:44 INFO - 1500957824823 Marionette DEBUG Closed connection 358
21:43:44 INFO - 1500957824825 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49992
21:43:44 INFO - 1500957824825 Marionette DEBUG Closed connection 359
21:43:44 INFO - 1500957824826 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49993
21:43:44 INFO - 1500957824827 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:44 INFO - 1500957824828 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:44 INFO - 1500957824830 Marionette DEBUG Register listener.js for window 4294967297
21:43:44 INFO - 1500957824837 Marionette TRACE 360 <- [1,1,null,{"sessionId":"594c9061-2fad-44fa-8e52-cb5f0404febd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:44 INFO - 1500957824838 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:43:44 INFO - 1500957824839 Marionette TRACE 360 <- [1,2,null,{}]
21:43:44 INFO - 1500957824840 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:44 INFO - 1500957824840 Marionette TRACE 360 <- [1,3,null,{}]
21:43:44 INFO - 1500957824841 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:44 INFO - 1500957824841 Marionette TRACE 360 <- [1,4,null,{}]
21:43:44 INFO - 1500957824843 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}]
21:43:44 INFO - TEST-START: test_click.py TestClick.test_container_element1500957824845 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:43:44 INFO - 1500957824846 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:43:44 INFO - 1500957824847 Marionette TRACE 360 <- [1,6,null,{}]
21:43:44 INFO - 1500957824849 Marionette DEBUG Closed connection 360
21:43:44 INFO - 1500957824849 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49994
21:43:44 INFO - 1500957824850 Marionette DEBUG Closed connection 361
21:43:44 INFO - 1500957824851 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49995
21:43:44 INFO - 1500957824852 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:44 INFO - 1500957824852 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:44 INFO - 1500957824854 Marionette DEBUG Register listener.js for window 4294967297
21:43:44 INFO - 1500957824857 Marionette TRACE 362 <- [1,1,null,{"sessionId":"10cc205f-19db-4c59-8691-07e972fa9039","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:44 INFO - 1500957824858 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:43:44 INFO - 1500957824860 Marionette TRACE 362 <- [1,2,null,{}]
21:43:44 INFO - 1500957824861 Marionette DEBUG Closed connection 362
21:43:44 INFO - 1500957824862 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49996
21:43:44 INFO - 1500957824863 Marionette DEBUG Closed connection 363
21:43:44 INFO - 1500957824863 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49997
21:43:44 INFO - 1500957824864 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:44 INFO - 1500957824865 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:44 INFO - 1500957824867 Marionette DEBUG Register listener.js for window 4294967297
21:43:44 INFO - 1500957824870 Marionette TRACE 364 <- [1,1,null,{"sessionId":"08594217-5d00-4f5d-b5f3-8f78872b0389","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:44 INFO - 1500957824871 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"}]
21:43:44 INFO - 1500957824872 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49546/test.html"
21:43:44 INFO - 1500957824874 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49546/test.html"
21:43:44 INFO - 1500957824875 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49546/test.html"
21:43:44 INFO - 1500957824880 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"
21:43:44 INFO - 1500957824883 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"
21:43:44 INFO - 1500957824895 Marionette TRACE 364 <- [1,2,null,{}]
21:43:44 INFO - 1500957824907 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:43:44 INFO - 1500957824913 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"453b7664-3a18-4735-869a-600f1f675344","ELEMENT":"453b7664-3a18-4735-869a-600f1f675344"}}]
21:43:44 INFO - 1500957824914 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"453b7664-3a18-4735-869a-600f1f675344"}]
21:43:45 INFO - 1500957825151 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:45 INFO - 1500957825152 Marionette TRACE 364 <- [1,4,null,{}]
21:43:45 INFO - 1500957825153 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"453b7664-3a18-4735-869a-600f1f675344","name":"selected"}]
21:43:45 INFO - 1500957825157 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:43:45 INFO - 1500957825158 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}]
21:43:45 INFO - TEST-END: test_click.py TestClick.test_container_element1500957825161 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:43:45 INFO - 1500957825162 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:43:45 INFO - 1500957825163 Marionette TRACE 364 <- [1,7,null,{}]
21:43:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 341ms
21:43:45 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:43:45 INFO - 1500957825165 Marionette DEBUG Closed connection 364
21:43:45 INFO - 1500957825166 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49998
21:43:45 INFO - 1500957825166 Marionette DEBUG Closed connection 365
21:43:45 INFO - 1500957825167 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49999
21:43:45 INFO - 1500957825168 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:45 INFO - 1500957825169 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825171 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825174 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e00d48f9-02b0-4632-967f-783f8335f903","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825175 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:43:45 INFO - 1500957825175 Marionette TRACE 366 <- [1,2,null,{}]
21:43:45 INFO - 1500957825176 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:45 INFO - 1500957825176 Marionette TRACE 366 <- [1,3,null,{}]
21:43:45 INFO - 1500957825177 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:45 INFO - 1500957825178 Marionette TRACE 366 <- [1,4,null,{}]
21:43:45 INFO - 1500957825179 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}]
21:43:45 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500957825182 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:43:45 INFO - 1500957825183 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:43:45 INFO - 1500957825184 Marionette TRACE 366 <- [1,6,null,{}]
21:43:45 INFO - 1500957825185 Marionette DEBUG Closed connection 366
21:43:45 INFO - 1500957825186 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50000
21:43:45 INFO - 1500957825186 Marionette DEBUG Closed connection 367
21:43:45 INFO - 1500957825187 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50001
21:43:45 INFO - 1500957825188 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:45 INFO - 1500957825188 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825190 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825193 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c443a40a-a134-420d-a502-6a5a44423900","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825194 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:43:45 INFO - 1500957825195 Marionette TRACE 368 <- [1,2,null,{}]
21:43:45 INFO - 1500957825197 Marionette DEBUG Closed connection 368
21:43:45 INFO - 1500957825197 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50002
21:43:45 INFO - 1500957825198 Marionette DEBUG Closed connection 369
21:43:45 INFO - 1500957825198 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50003
21:43:45 INFO - 1500957825199 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:45 INFO - 1500957825200 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825202 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825205 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c1a1ffb6-ee5b-473f-916e-e5893f234ee8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825206 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"}]
21:43:45 INFO - 1500957825207 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"
21:43:45 INFO - 1500957825209 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"
21:43:45 INFO - 1500957825210 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"
21:43:45 INFO - 1500957825215 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"
21:43:45 INFO - 1500957825218 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"
21:43:45 INFO - 1500957825220 Marionette TRACE 370 <- [1,2,null,{}]
21:43:45 INFO - 1500957825228 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:43:45 INFO - 1500957825231 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c43f84e0-68d4-4c53-b7ef-646e7d8b05e1","ELEMENT":"c43f84e0-68d4-4c53-b7ef-646e7d8b05e1"}}]
21:43:45 INFO - 1500957825232 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"c43f84e0-68d4-4c53-b7ef-646e7d8b05e1"}]
21:43:45 INFO - 1500957825471 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:45 INFO - 1500957825471 Marionette TRACE 370 <- [1,4,null,{}]
21:43:45 INFO - 1500957825473 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"c43f84e0-68d4-4c53-b7ef-646e7d8b05e1","name":"selected"}]
21:43:45 INFO - 1500957825476 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:43:45 INFO - 1500957825478 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}]
21:43:45 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500957825480 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:43:45 INFO - 1500957825481 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:43:45 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 319ms
21:43:45 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:43:45 INFO - 1500957825483 Marionette TRACE 370 <- [1,7,null,{}]
21:43:45 INFO - 1500957825484 Marionette DEBUG Closed connection 370
21:43:45 INFO - 1500957825485 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50004
21:43:45 INFO - 1500957825486 Marionette DEBUG Closed connection 371
21:43:45 INFO - 1500957825486 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50005
21:43:45 INFO - 1500957825487 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:45 INFO - 1500957825488 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825490 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825493 Marionette TRACE 372 <- [1,1,null,{"sessionId":"69100257-86d2-4ce6-9e69-60a0939e1812","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825494 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:43:45 INFO - 1500957825494 Marionette TRACE 372 <- [1,2,null,{}]
21:43:45 INFO - 1500957825495 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:45 INFO - 1500957825496 Marionette TRACE 372 <- [1,3,null,{}]
21:43:45 INFO - 1500957825496 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:45 INFO - 1500957825497 Marionette TRACE 372 <- [1,4,null,{}]
21:43:45 INFO - 1500957825498 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}]
21:43:45 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500957825501 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:43:45 INFO - 1500957825502 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:43:45 INFO - 1500957825503 Marionette TRACE 372 <- [1,6,null,{}]
21:43:45 INFO - 1500957825504 Marionette DEBUG Closed connection 372
21:43:45 INFO - 1500957825505 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50006
21:43:45 INFO - 1500957825505 Marionette DEBUG Closed connection 373
21:43:45 INFO - 1500957825506 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50007
21:43:45 INFO - 1500957825507 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:45 INFO - 1500957825508 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825510 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825512 Marionette TRACE 374 <- [1,1,null,{"sessionId":"58d5826b-bc1c-4f9d-8993-e0ca8c7a8ea5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825514 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:43:45 INFO - 1500957825515 Marionette TRACE 374 <- [1,2,null,{}]
21:43:45 INFO - 1500957825516 Marionette DEBUG Closed connection 374
21:43:45 INFO - 1500957825517 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50008
21:43:45 INFO - 1500957825518 Marionette DEBUG Closed connection 375
21:43:45 INFO - 1500957825518 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50009
21:43:45 INFO - 1500957825519 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:45 INFO - 1500957825520 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825522 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825525 Marionette TRACE 376 <- [1,1,null,{"sessionId":"88205b13-35cd-4bc8-8940-4d31e9a7acfb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825526 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"}]
21:43:45 INFO - 1500957825528 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"
21:43:45 INFO - 1500957825530 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"
21:43:45 INFO - 1500957825530 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"
21:43:45 INFO - 1500957825536 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"
21:43:45 INFO - 1500957825538 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"
21:43:45 INFO - 1500957825546 Marionette TRACE 376 <- [1,2,null,{}]
21:43:45 INFO - 1500957825550 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:43:45 INFO - 1500957825553 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"604ce649-79ba-4609-b0df-1c282464da9e","ELEMENT":"604ce649-79ba-4609-b0df-1c282464da9e"}}]
21:43:45 INFO - 1500957825554 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"604ce649-79ba-4609-b0df-1c282464da9e"}]
21:43:45 INFO - 1500957825797 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:45 INFO - 1500957825797 Marionette TRACE 376 <- [1,4,null,{}]
21:43:45 INFO - 1500957825799 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}]
21:43:45 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500957825802 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:43:45 INFO - 1500957825803 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:43:45 INFO - 1500957825804 Marionette TRACE 376 <- [1,6,null,{}]
21:43:45 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
21:43:45 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:43:45 INFO - 1500957825806 Marionette DEBUG Closed connection 376
21:43:45 INFO - 1500957825807 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50010
21:43:45 INFO - 1500957825808 Marionette DEBUG Closed connection 377
21:43:45 INFO - 1500957825808 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50011
21:43:45 INFO - 1500957825809 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:45 INFO - 1500957825810 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825812 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825815 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e0356d07-2cfb-4a95-92ed-659ce1e2c86a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825816 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:43:45 INFO - 1500957825816 Marionette TRACE 378 <- [1,2,null,{}]
21:43:45 INFO - 1500957825817 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:45 INFO - 1500957825817 Marionette TRACE 378 <- [1,3,null,{}]
21:43:45 INFO - 1500957825818 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:45 INFO - 1500957825819 Marionette TRACE 378 <- [1,4,null,{}]
21:43:45 INFO - 1500957825820 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}]
21:43:45 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500957825823 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:43:45 INFO - 1500957825823 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:43:45 INFO - 1500957825824 Marionette TRACE 378 <- [1,6,null,{}]
21:43:45 INFO - 1500957825826 Marionette DEBUG Closed connection 378
21:43:45 INFO - 1500957825826 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50012
21:43:45 INFO - 1500957825827 Marionette DEBUG Closed connection 379
21:43:45 INFO - 1500957825828 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50013
21:43:45 INFO - 1500957825829 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:45 INFO - 1500957825829 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825831 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825834 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3ae24339-5385-4159-a3c6-0488f4202f0a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825835 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:43:45 INFO - 1500957825836 Marionette TRACE 380 <- [1,2,null,{}]
21:43:45 INFO - 1500957825837 Marionette DEBUG Closed connection 380
21:43:45 INFO - 1500957825838 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50014
21:43:45 INFO - 1500957825839 Marionette DEBUG Closed connection 381
21:43:45 INFO - 1500957825840 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50015
21:43:45 INFO - 1500957825841 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:45 INFO - 1500957825841 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:45 INFO - 1500957825843 Marionette DEBUG Register listener.js for window 4294967297
21:43:45 INFO - 1500957825846 Marionette TRACE 382 <- [1,1,null,{"sessionId":"70eb44fb-90d5-43f9-881f-57fd2fb56d2c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:45 INFO - 1500957825847 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"}]
21:43:45 INFO - 1500957825848 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"
21:43:45 INFO - 1500957825851 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"
21:43:45 INFO - 1500957825852 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"
21:43:45 INFO - 1500957825859 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"
21:43:45 INFO - 1500957825863 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"
21:43:45 INFO - 1500957825866 Marionette TRACE 382 <- [1,2,null,{}]
21:43:45 INFO - 1500957825869 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:43:45 INFO - 1500957825876 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f5b7103f-eafa-4da2-82d0-1905bfe9fe36","ELEMENT":"f5b7103f-eafa-4da2-82d0-1905bfe9fe36"}}]
21:43:45 INFO - 1500957825877 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f5b7103f-eafa-4da2-82d0-1905bfe9fe36"}]
21:43:46 INFO - 1500957826114 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:46 INFO - 1500957826114 Marionette TRACE 382 <- [1,4,null,{}]
21:43:46 INFO - 1500957826116 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}]
21:43:46 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500957826119 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:43:46 INFO - 1500957826120 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:43:46 INFO - 1500957826121 Marionette TRACE 382 <- [1,6,null,{}]
21:43:46 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 317ms
21:43:46 INFO - TEST-START | test_click.py TestClick.test_input_file
21:43:46 INFO - 1500957826122 Marionette DEBUG Closed connection 382
21:43:46 INFO - 1500957826124 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50016
21:43:46 INFO - 1500957826125 Marionette DEBUG Closed connection 383
21:43:46 INFO - 1500957826125 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50017
21:43:46 INFO - 1500957826126 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:46 INFO - 1500957826127 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826129 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826139 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5d22169d-26b6-487b-8f04-e363cf4e367f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826140 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:43:46 INFO - 1500957826140 Marionette TRACE 384 <- [1,2,null,{}]
21:43:46 INFO - 1500957826141 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:46 INFO - 1500957826141 Marionette TRACE 384 <- [1,3,null,{}]
21:43:46 INFO - 1500957826142 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:46 INFO - 1500957826142 Marionette TRACE 384 <- [1,4,null,{}]
21:43:46 INFO - 1500957826144 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}]
21:43:46 INFO - TEST-START: test_click.py TestClick.test_input_file1500957826147 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:43:46 INFO - 1500957826148 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:43:46 INFO - 1500957826149 Marionette TRACE 384 <- [1,6,null,{}]
21:43:46 INFO - 1500957826150 Marionette DEBUG Closed connection 384
21:43:46 INFO - 1500957826151 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50018
21:43:46 INFO - 1500957826151 Marionette DEBUG Closed connection 385
21:43:46 INFO - 1500957826152 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50019
21:43:46 INFO - 1500957826153 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:46 INFO - 1500957826154 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826156 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826159 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5805900d-eb3b-449c-81d6-61e3dac7b347","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826160 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:43:46 INFO - 1500957826161 Marionette TRACE 386 <- [1,2,null,{}]
21:43:46 INFO - 1500957826162 Marionette DEBUG Closed connection 386
21:43:46 INFO - 1500957826163 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50020
21:43:46 INFO - 1500957826163 Marionette DEBUG Closed connection 387
21:43:46 INFO - 1500957826164 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50021
21:43:46 INFO - 1500957826165 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:46 INFO - 1500957826166 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826168 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826170 Marionette TRACE 388 <- [1,1,null,{"sessionId":"01f7ce78-b7e9-4448-9dfc-4846f8be7de1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826171 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:43:46 INFO - 1500957826173 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"
21:43:46 INFO - 1500957826175 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"
21:43:46 INFO - 1500957826175 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"
21:43:46 INFO - 1500957826181 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:46 INFO - 1500957826184 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:46 INFO - 1500957826186 Marionette TRACE 388 <- [1,2,null,{}]
21:43:46 INFO - 1500957826194 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:43:46 INFO - 1500957826199 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"82cee3f4-7cd8-4f3f-9fe1-0b5c2dba8d2a","ELEMENT":"82cee3f4-7cd8-4f3f-9fe1-0b5c2dba8d2a"}}]
21:43:46 INFO - 1500957826200 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"82cee3f4-7cd8-4f3f-9fe1-0b5c2dba8d2a"}]
21:43:46 INFO - 1500957826223 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]
21:43:46 INFO - 1500957826225 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}]
21:43:46 INFO - TEST-END: test_click.py TestClick.test_input_file1500957826227 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:43:46 INFO - 1500957826228 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:43:46 INFO - 1500957826229 Marionette TRACE 388 <- [1,6,null,{}]
21:43:46 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 108ms
21:43:46 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:43:46 INFO - 1500957826231 Marionette DEBUG Closed connection 388
21:43:46 INFO - 1500957826232 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50022
21:43:46 INFO - 1500957826232 Marionette DEBUG Closed connection 389
21:43:46 INFO - 1500957826233 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50023
21:43:46 INFO - 1500957826234 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:46 INFO - 1500957826235 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826237 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826240 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9074c345-8d7c-4f80-89cb-6505a62198af","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826241 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:43:46 INFO - 1500957826241 Marionette TRACE 390 <- [1,2,null,{}]
21:43:46 INFO - 1500957826242 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:46 INFO - 1500957826242 Marionette TRACE 390 <- [1,3,null,{}]
21:43:46 INFO - 1500957826243 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:46 INFO - 1500957826244 Marionette TRACE 390 <- [1,4,null,{}]
21:43:46 INFO - 1500957826245 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}]
21:43:46 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500957826248 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:43:46 INFO - 1500957826249 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:43:46 INFO - 1500957826250 Marionette TRACE 390 <- [1,6,null,{}]
21:43:46 INFO - 1500957826251 Marionette DEBUG Closed connection 390
21:43:46 INFO - 1500957826252 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50024
21:43:46 INFO - 1500957826252 Marionette DEBUG Closed connection 391
21:43:46 INFO - 1500957826253 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50025
21:43:46 INFO - 1500957826254 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:46 INFO - 1500957826254 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826256 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826259 Marionette TRACE 392 <- [1,1,null,{"sessionId":"274c34b9-b1fc-4a2f-97be-e2389651bd47","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826261 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:43:46 INFO - 1500957826262 Marionette TRACE 392 <- [1,2,null,{}]
21:43:46 INFO - 1500957826263 Marionette DEBUG Closed connection 392
21:43:46 INFO - 1500957826264 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50026
21:43:46 INFO - 1500957826264 Marionette DEBUG Closed connection 393
21:43:46 INFO - 1500957826265 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50027
21:43:46 INFO - 1500957826266 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:46 INFO - 1500957826267 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826269 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826272 Marionette TRACE 394 <- [1,1,null,{"sessionId":"addcc70d-5945-40ad-bb73-ef657056c9e4","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826273 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"}]
21:43:46 INFO - 1500957826274 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:46 INFO - 1500957826276 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:46 INFO - 1500957826277 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:46 INFO - 1500957826283 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"
21:43:46 INFO - 1500957826284 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"
21:43:46 INFO - 1500957826287 Marionette TRACE 394 <- [1,2,null,{}]
21:43:46 INFO - 1500957826295 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:43:46 INFO - 1500957826298 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"81dfa828-56e3-48d4-84fd-87cc432b8f5f","ELEMENT":"81dfa828-56e3-48d4-84fd-87cc432b8f5f"}}]
21:43:46 INFO - 1500957826299 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:43:46 INFO - 1500957826301 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c7ad1574-0c22-4ef4-9eae-8c5cc23879b2","ELEMENT":"c7ad1574-0c22-4ef4-9eae-8c5cc23879b2"}}]
21:43:46 INFO - 1500957826302 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"81dfa828-56e3-48d4-84fd-87cc432b8f5f"}]
21:43:46 INFO - 1500957826541 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:46 INFO - 1500957826542 Marionette TRACE 394 <- [1,5,null,{}]
21:43:46 INFO - 1500957826543 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c7ad1574-0c22-4ef4-9eae-8c5cc23879b2"}]
21:43:46 INFO - 1500957826556 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]
21:43:46 INFO - 1500957826558 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:43:46 INFO - 1500957826561 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:43:46 INFO - 1500957826562 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}]
21:43:46 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500957826565 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:43:46 INFO - 1500957826566 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:43:46 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
21:43:46 INFO - 1500957826567 Marionette TRACE 394 <- [1,9,null,{}]
21:43:46 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:43:46 INFO - 1500957826568 Marionette DEBUG Closed connection 394
21:43:46 INFO - 1500957826570 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50028
21:43:46 INFO - 1500957826570 Marionette DEBUG Closed connection 395
21:43:46 INFO - 1500957826572 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50029
21:43:46 INFO - 1500957826573 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:46 INFO - 1500957826573 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826575 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826578 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6db18d39-e62f-4517-b6b8-cb3e3d5f03ad","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826579 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:43:46 INFO - 1500957826580 Marionette TRACE 396 <- [1,2,null,{}]
21:43:46 INFO - 1500957826581 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:46 INFO - 1500957826581 Marionette TRACE 396 <- [1,3,null,{}]
21:43:46 INFO - 1500957826582 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:46 INFO - 1500957826582 Marionette TRACE 396 <- [1,4,null,{}]
21:43:46 INFO - 1500957826584 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}]
21:43:46 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500957826586 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:43:46 INFO - 1500957826587 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:43:46 INFO - 1500957826588 Marionette TRACE 396 <- [1,6,null,{}]
21:43:46 INFO - 1500957826590 Marionette DEBUG Closed connection 396
21:43:46 INFO - 1500957826590 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50030
21:43:46 INFO - 1500957826591 Marionette DEBUG Closed connection 397
21:43:46 INFO - 1500957826592 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50031
21:43:46 INFO - 1500957826592 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:46 INFO - 1500957826593 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826595 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826598 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1c9de954-00db-40ed-96e3-cf27b21ad935","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826598 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:43:46 INFO - 1500957826600 Marionette TRACE 398 <- [1,2,null,{}]
21:43:46 INFO - 1500957826601 Marionette DEBUG Closed connection 398
21:43:46 INFO - 1500957826601 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50032
21:43:46 INFO - 1500957826602 Marionette DEBUG Closed connection 399
21:43:46 INFO - 1500957826603 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50033
21:43:46 INFO - 1500957826604 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:46 INFO - 1500957826604 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}
21:43:46 INFO - 1500957826606 Marionette DEBUG Register listener.js for window 4294967297
21:43:46 INFO - 1500957826609 Marionette TRACE 400 <- [1,1,null,{"sessionId":"76cc571b-88f0-4142-a932-b056060e953a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":900,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpmv_dmm.mozrunner","moz:accessibilityChecks":false}}]
21:43:46 INFO - 1500957826610 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"}]
21:43:46 INFO - 1500957826611 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"
21:43:46 INFO - 1500957826613 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"
21:43:46 INFO - 1500957826613 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"
21:43:46 INFO - 1500957826619 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"
21:43:46 INFO - 1500957826621 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"
21:43:46 INFO - 1500957826629 Marionette TRACE 400 <- [1,2,null,{}]
21:43:46 INFO - 1500957826633 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:43:46 INFO - 1500957826635 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"71e0619d-dec1-4c56-a7eb-16d5514f89a9","ELEMENT":"71e0619d-dec1-4c56-a7eb-16d5514f89a9"}}]
21:43:46 INFO - 1500957826636 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"71e0619d-dec1-4c56-a7eb-16d5514f89a9"}]
21:43:46 INFO - 1500957826657 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:43:46 INFO - 1500957826658 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"71e0619d-dec1-4c56-a7eb-16d5514f89a9"}]
21:43:46 INFO - 1500957826672 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element