could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:48:52 INFO - 1500659332638 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
10:48:52 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500659332642 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:48:52 INFO - 1500659332643 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:48:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 147ms
10:48:52 INFO - 1500659332645 Marionette TRACE 352 <- [1,6,null,{}]
10:48:52 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:48:52 INFO - 1500659332647 Marionette DEBUG Closed connection 352
10:48:52 INFO - 1500659332649 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49920
10:48:52 INFO - 1500659332649 Marionette DEBUG Closed connection 353
10:48:52 INFO - 1500659332650 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49921
10:48:52 INFO - 1500659332651 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:52 INFO - 1500659332652 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:52 INFO - 1500659332655 Marionette DEBUG Register listener.js for window 6442450945
10:48:52 INFO - 1500659332659 Marionette TRACE 354 <- [1,1,null,{"sessionId":"90518f24-488a-4e34-a6af-c2507b49bd9b","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:52 INFO - 1500659332660 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:48:52 INFO - 1500659332661 Marionette TRACE 354 <- [1,2,null,{}]
10:48:52 INFO - 1500659332662 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:48:52 INFO - 1500659332662 Marionette TRACE 354 <- [1,3,null,{}]
10:48:52 INFO - 1500659332663 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:48:52 INFO - 1500659332664 Marionette TRACE 354 <- [1,4,null,{}]
10:48:52 INFO - 1500659332665 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
10:48:52 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500659332668 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:48:52 INFO - 1500659332669 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:48:52 INFO - 1500659332671 Marionette TRACE 354 <- [1,6,null,{}]
10:48:52 INFO - 1500659332673 Marionette DEBUG Closed connection 354
10:48:52 INFO - 1500659332673 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49922
10:48:52 INFO - 1500659332674 Marionette DEBUG Closed connection 355
10:48:52 INFO - 1500659332675 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49923
10:48:52 INFO - 1500659332676 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:52 INFO - 1500659332677 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:52 INFO - 1500659332679 Marionette DEBUG Register listener.js for window 6442450945
10:48:52 INFO - 1500659332683 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d4172473-eb0d-48f1-843c-f6738e2d33ca","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:52 INFO - 1500659332684 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:48:52 INFO - 1500659332685 Marionette TRACE 356 <- [1,2,null,{}]
10:48:52 INFO - 1500659332687 Marionette DEBUG Closed connection 356
10:48:52 INFO - 1500659332688 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49924
10:48:52 INFO - 1500659332689 Marionette DEBUG Closed connection 357
10:48:52 INFO - 1500659332690 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49925
10:48:52 INFO - 1500659332691 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:48:52 INFO - 1500659332691 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:52 INFO - 1500659332694 Marionette DEBUG Register listener.js for window 6442450945
10:48:52 INFO - 1500659332698 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b9a52b26-2b22-4b41-b5f6-0f0ba853d654","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:52 INFO - 1500659332699 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49478/clicks.html"}]
10:48:52 INFO - 1500659332701 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49478/hidden.html"
10:48:52 INFO - 1500659332712 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49478/hidden.html"
10:48:52 INFO - 1500659332713 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49478/hidden.html"
10:48:52 INFO - 1500659332720 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49478/clicks.html"
10:48:52 INFO - 1500659332727 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49478/clicks.html"
10:48:52 INFO - 1500659332730 Marionette TRACE 358 <- [1,2,null,{}]
10:48:52 INFO - 1500659332735 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:48:52 INFO - 1500659332738 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"babcae87-504f-4e98-9b6f-1705bbcdbc23","ELEMENT":"babcae87-504f-4e98-9b6f-1705bbcdbc23"}}]
10:48:52 INFO - 1500659332743 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"babcae87-504f-4e98-9b6f-1705bbcdbc23"}]
10:48:52 INFO - 1500659332789 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49478/clicks.html"
10:48:52 INFO - 1500659332798 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49478/clicks.html"
10:48:52 INFO - 1500659332798 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49478/clicks.html"
10:48:52 INFO - 1500659332809 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49478/test.html"
10:48:52 INFO - 1500659332811 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49478/test.html"
10:48:52 INFO - 1500659332818 Marionette TRACE 358 <- [1,4,null,{}]
10:48:52 INFO - 1500659332828 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:48:52 INFO - 1500659332833 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2adc5c5a-36dd-4f08-9a54-27a9162df999","ELEMENT":"2adc5c5a-36dd-4f08-9a54-27a9162df999"}}]
10:48:52 INFO - 1500659332835 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:48:52 INFO - 1500659332835 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:48:52 INFO - 1500659332837 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
10:48:52 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500659332841 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:48:52 INFO - 1500659332842 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:48:52 INFO - 1500659332843 Marionette TRACE 358 <- [1,8,null,{}]
10:48:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 198ms
10:48:52 INFO - TEST-START | test_click.py TestClick.test_container_element
10:48:52 INFO - 1500659332845 Marionette DEBUG Closed connection 358
10:48:52 INFO - 1500659332846 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49928
10:48:52 INFO - 1500659332847 Marionette DEBUG Closed connection 359
10:48:52 INFO - 1500659332848 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49929
10:48:52 INFO - 1500659332849 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:52 INFO - 1500659332849 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:52 INFO - 1500659332852 Marionette DEBUG Register listener.js for window 6442450945
10:48:52 INFO - 1500659332856 Marionette TRACE 360 <- [1,1,null,{"sessionId":"6ad8e54f-cf81-4bde-ba45-c65515053c22","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:52 INFO - 1500659332857 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:48:52 INFO - 1500659332858 Marionette TRACE 360 <- [1,2,null,{}]
10:48:52 INFO - 1500659332859 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:48:52 INFO - 1500659332859 Marionette TRACE 360 <- [1,3,null,{}]
10:48:52 INFO - 1500659332860 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:48:52 INFO - 1500659332861 Marionette TRACE 360 <- [1,4,null,{}]
10:48:52 INFO - 1500659332862 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
10:48:52 INFO - TEST-START: test_click.py TestClick.test_container_element1500659332865 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:48:52 INFO - 1500659332866 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:48:52 INFO - 1500659332868 Marionette TRACE 360 <- [1,6,null,{}]
10:48:52 INFO - 1500659332869 Marionette DEBUG Closed connection 360
10:48:52 INFO - 1500659332870 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49930
10:48:52 INFO - 1500659332871 Marionette DEBUG Closed connection 361
10:48:52 INFO - 1500659332872 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49931
10:48:52 INFO - 1500659332873 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:52 INFO - 1500659332873 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:52 INFO - 1500659332876 Marionette DEBUG Register listener.js for window 6442450945
10:48:52 INFO - 1500659332879 Marionette TRACE 362 <- [1,1,null,{"sessionId":"0da04031-ef73-4466-9650-0b6323ad2f3b","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:52 INFO - 1500659332881 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:48:52 INFO - 1500659332882 Marionette TRACE 362 <- [1,2,null,{}]
10:48:52 INFO - 1500659332884 Marionette DEBUG Closed connection 362
10:48:52 INFO - 1500659332884 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49932
10:48:52 INFO - 1500659332885 Marionette DEBUG Closed connection 363
10:48:52 INFO - 1500659332886 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49933
10:48:52 INFO - 1500659332888 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:48:52 INFO - 1500659332888 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:52 INFO - 1500659332891 Marionette DEBUG Register listener.js for window 6442450945
10:48:52 INFO - 1500659332894 Marionette TRACE 364 <- [1,1,null,{"sessionId":"ae29d9c1-1e05-4e7c-9eb0-dcdce7f88e05","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:52 INFO - 1500659332896 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:48:52 INFO - 1500659332898 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49478/test.html"
10:48:52 INFO - 1500659332901 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49478/test.html"
10:48:52 INFO - 1500659332902 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49478/test.html"
10:48:52 INFO - 1500659332912 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:52 INFO - 1500659332916 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:52 INFO - 1500659332933 Marionette TRACE 364 <- [1,2,null,{}]
10:48:52 INFO - 1500659332948 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:48:52 INFO - 1500659332951 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40b10f73-6ee1-4d93-976f-e2eba36359bc","ELEMENT":"40b10f73-6ee1-4d93-976f-e2eba36359bc"}}]
10:48:52 INFO - 1500659332952 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"40b10f73-6ee1-4d93-976f-e2eba36359bc"}]
10:48:53 INFO - 1500659333197 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:48:53 INFO - 1500659333198 Marionette TRACE 364 <- [1,4,null,{}]
10:48:53 INFO - 1500659333199 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"40b10f73-6ee1-4d93-976f-e2eba36359bc","name":"selected"}]
10:48:53 INFO - 1500659333204 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:48:53 INFO - 1500659333207 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
10:48:53 INFO - TEST-END: test_click.py TestClick.test_container_element1500659333210 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:48:53 INFO - 1500659333211 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:48:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 369ms
10:48:53 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:48:53 INFO - 1500659333213 Marionette TRACE 364 <- [1,7,null,{}]
10:48:53 INFO - 1500659333215 Marionette DEBUG Closed connection 364
10:48:53 INFO - 1500659333215 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49934
10:48:53 INFO - 1500659333216 Marionette DEBUG Closed connection 365
10:48:53 INFO - 1500659333217 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49935
10:48:53 INFO - 1500659333218 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:53 INFO - 1500659333219 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333222 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333225 Marionette TRACE 366 <- [1,1,null,{"sessionId":"5d135ac6-3ae8-4147-b592-eddcdb2e6207","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333227 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:48:53 INFO - 1500659333227 Marionette TRACE 366 <- [1,2,null,{}]
10:48:53 INFO - 1500659333228 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:48:53 INFO - 1500659333229 Marionette TRACE 366 <- [1,3,null,{}]
10:48:53 INFO - 1500659333230 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:48:53 INFO - 1500659333230 Marionette TRACE 366 <- [1,4,null,{}]
10:48:53 INFO - 1500659333232 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
10:48:53 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500659333235 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:48:53 INFO - 1500659333236 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:48:53 INFO - 1500659333238 Marionette TRACE 366 <- [1,6,null,{}]
10:48:53 INFO - 1500659333239 Marionette DEBUG Closed connection 366
10:48:53 INFO - 1500659333240 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49936
10:48:53 INFO - 1500659333241 Marionette DEBUG Closed connection 367
10:48:53 INFO - 1500659333242 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49937
10:48:53 INFO - 1500659333243 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:53 INFO - 1500659333243 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333246 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333250 Marionette TRACE 368 <- [1,1,null,{"sessionId":"1447f409-0bb6-4f5b-b1a3-64fcd8a873f2","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333251 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:48:53 INFO - 1500659333252 Marionette TRACE 368 <- [1,2,null,{}]
10:48:53 INFO - 1500659333254 Marionette DEBUG Closed connection 368
10:48:53 INFO - 1500659333255 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49938
10:48:53 INFO - 1500659333256 Marionette DEBUG Closed connection 369
10:48:53 INFO - 1500659333256 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49939
10:48:53 INFO - 1500659333257 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:48:53 INFO - 1500659333258 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333261 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333264 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c752e7b5-f563-4c03-a6a2-4f7d1540b430","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333266 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:48:53 INFO - 1500659333268 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:53 INFO - 1500659333270 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:53 INFO - 1500659333271 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:53 INFO - 1500659333278 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:53 INFO - 1500659333281 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:53 INFO - 1500659333295 Marionette TRACE 370 <- [1,2,null,{}]
10:48:53 INFO - 1500659333302 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:48:53 INFO - 1500659333305 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bab60410-b99e-4400-ac3b-f1424780c99f","ELEMENT":"bab60410-b99e-4400-ac3b-f1424780c99f"}}]
10:48:53 INFO - 1500659333311 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"bab60410-b99e-4400-ac3b-f1424780c99f"}]
10:48:53 INFO - 1500659333569 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:48:53 INFO - 1500659333570 Marionette TRACE 370 <- [1,4,null,{}]
10:48:53 INFO - 1500659333571 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"bab60410-b99e-4400-ac3b-f1424780c99f","name":"selected"}]
10:48:53 INFO - 1500659333576 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:48:53 INFO - 1500659333578 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
10:48:53 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500659333581 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:48:53 INFO - 1500659333582 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:48:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 370ms
10:48:53 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:48:53 INFO - 1500659333583 Marionette TRACE 370 <- [1,7,null,{}]
10:48:53 INFO - 1500659333585 Marionette DEBUG Closed connection 370
10:48:53 INFO - 1500659333586 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49940
10:48:53 INFO - 1500659333587 Marionette DEBUG Closed connection 371
10:48:53 INFO - 1500659333587 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49941
10:48:53 INFO - 1500659333589 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:53 INFO - 1500659333589 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333592 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333596 Marionette TRACE 372 <- [1,1,null,{"sessionId":"450a3a44-a551-409c-942b-e7ae30b1a04b","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333597 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:48:53 INFO - 1500659333598 Marionette TRACE 372 <- [1,2,null,{}]
10:48:53 INFO - 1500659333599 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:48:53 INFO - 1500659333599 Marionette TRACE 372 <- [1,3,null,{}]
10:48:53 INFO - 1500659333600 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:48:53 INFO - 1500659333601 Marionette TRACE 372 <- [1,4,null,{}]
10:48:53 INFO - 1500659333602 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
10:48:53 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500659333605 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:48:53 INFO - 1500659333606 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:48:53 INFO - 1500659333608 Marionette TRACE 372 <- [1,6,null,{}]
10:48:53 INFO - 1500659333609 Marionette DEBUG Closed connection 372
10:48:53 INFO - 1500659333610 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49942
10:48:53 INFO - 1500659333611 Marionette DEBUG Closed connection 373
10:48:53 INFO - 1500659333612 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49943
10:48:53 INFO - 1500659333613 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:53 INFO - 1500659333613 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333616 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333619 Marionette TRACE 374 <- [1,1,null,{"sessionId":"52c11468-9172-4b17-bd14-8a7f09d00e3c","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333621 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:48:53 INFO - 1500659333622 Marionette TRACE 374 <- [1,2,null,{}]
10:48:53 INFO - 1500659333624 Marionette DEBUG Closed connection 374
10:48:53 INFO - 1500659333625 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49944
10:48:53 INFO - 1500659333626 Marionette DEBUG Closed connection 375
10:48:53 INFO - 1500659333626 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49945
10:48:53 INFO - 1500659333628 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:48:53 INFO - 1500659333628 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333631 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333642 Marionette TRACE 376 <- [1,1,null,{"sessionId":"57964aa6-750a-498a-987e-ead8f1862b15","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333643 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
10:48:53 INFO - 1500659333646 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:53 INFO - 1500659333649 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:53 INFO - 1500659333649 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:53 INFO - 1500659333659 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:48:53 INFO - 1500659333661 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:48:53 INFO - 1500659333670 Marionette TRACE 376 <- [1,2,null,{}]
10:48:53 INFO - 1500659333679 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:48:53 INFO - 1500659333684 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e5610a1e-e0c1-4105-8635-d6268c3ffb04","ELEMENT":"e5610a1e-e0c1-4105-8635-d6268c3ffb04"}}]
10:48:53 INFO - 1500659333686 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e5610a1e-e0c1-4105-8635-d6268c3ffb04"}]
10:48:53 INFO - 1500659333928 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:48:53 INFO - 1500659333929 Marionette TRACE 376 <- [1,4,null,{}]
10:48:53 INFO - 1500659333931 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
10:48:53 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500659333934 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:48:53 INFO - 1500659333935 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:48:53 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 353ms
10:48:53 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:48:53 INFO - 1500659333937 Marionette TRACE 376 <- [1,6,null,{}]
10:48:53 INFO - 1500659333939 Marionette DEBUG Closed connection 376
10:48:53 INFO - 1500659333940 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49946
10:48:53 INFO - 1500659333940 Marionette DEBUG Closed connection 377
10:48:53 INFO - 1500659333941 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49947
10:48:53 INFO - 1500659333942 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:53 INFO - 1500659333943 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333946 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333950 Marionette TRACE 378 <- [1,1,null,{"sessionId":"2a52d35a-e986-4c4d-93d6-874a7efd2d47","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333951 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:48:53 INFO - 1500659333951 Marionette TRACE 378 <- [1,2,null,{}]
10:48:53 INFO - 1500659333952 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:48:53 INFO - 1500659333953 Marionette TRACE 378 <- [1,3,null,{}]
10:48:53 INFO - 1500659333954 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:48:53 INFO - 1500659333954 Marionette TRACE 378 <- [1,4,null,{}]
10:48:53 INFO - 1500659333956 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
10:48:53 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500659333959 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:48:53 INFO - 1500659333960 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:48:53 INFO - 1500659333961 Marionette TRACE 378 <- [1,6,null,{}]
10:48:53 INFO - 1500659333963 Marionette DEBUG Closed connection 378
10:48:53 INFO - 1500659333964 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49948
10:48:53 INFO - 1500659333965 Marionette DEBUG Closed connection 379
10:48:53 INFO - 1500659333965 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49949
10:48:53 INFO - 1500659333966 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:53 INFO - 1500659333967 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333970 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333973 Marionette TRACE 380 <- [1,1,null,{"sessionId":"18f10bbb-5035-4161-ac70-25fdf1e24059","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333974 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:48:53 INFO - 1500659333976 Marionette TRACE 380 <- [1,2,null,{}]
10:48:53 INFO - 1500659333978 Marionette DEBUG Closed connection 380
10:48:53 INFO - 1500659333978 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49950
10:48:53 INFO - 1500659333979 Marionette DEBUG Closed connection 381
10:48:53 INFO - 1500659333980 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49951
10:48:53 INFO - 1500659333981 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:48:53 INFO - 1500659333982 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:53 INFO - 1500659333984 Marionette DEBUG Register listener.js for window 6442450945
10:48:53 INFO - 1500659333988 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2725eb48-593f-4594-a279-d6e28c28f4d1","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:53 INFO - 1500659333989 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:48:53 INFO - 1500659333991 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:48:53 INFO - 1500659333993 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:48:53 INFO - 1500659333993 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:48:54 INFO - 1500659334000 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:54 INFO - 1500659334003 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:54 INFO - 1500659334013 Marionette TRACE 382 <- [1,2,null,{}]
10:48:54 INFO - 1500659334018 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:48:54 INFO - 1500659334021 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"92f68fae-6122-4a71-9642-3c6d3508f53b","ELEMENT":"92f68fae-6122-4a71-9642-3c6d3508f53b"}}]
10:48:54 INFO - 1500659334022 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"92f68fae-6122-4a71-9642-3c6d3508f53b"}]
10:48:54 INFO - 1500659334265 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:48:54 INFO - 1500659334266 Marionette TRACE 382 <- [1,4,null,{}]
10:48:54 INFO - 1500659334268 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
10:48:54 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500659334271 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:48:54 INFO - 1500659334272 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:48:54 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 336ms
10:48:54 INFO - TEST-START | test_click.py TestClick.test_input_file
10:48:54 INFO - 1500659334274 Marionette TRACE 382 <- [1,6,null,{}]
10:48:54 INFO - 1500659334276 Marionette DEBUG Closed connection 382
10:48:54 INFO - 1500659334277 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49952
10:48:54 INFO - 1500659334277 Marionette DEBUG Closed connection 383
10:48:54 INFO - 1500659334278 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49953
10:48:54 INFO - 1500659334279 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:54 INFO - 1500659334280 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334283 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334286 Marionette TRACE 384 <- [1,1,null,{"sessionId":"2edd3731-a04e-4aaa-b1c5-e0bc619015f8","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334288 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:48:54 INFO - 1500659334288 Marionette TRACE 384 <- [1,2,null,{}]
10:48:54 INFO - 1500659334289 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:48:54 INFO - 1500659334290 Marionette TRACE 384 <- [1,3,null,{}]
10:48:54 INFO - 1500659334290 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:48:54 INFO - 1500659334291 Marionette TRACE 384 <- [1,4,null,{}]
10:48:54 INFO - 1500659334293 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
10:48:54 INFO - TEST-START: test_click.py TestClick.test_input_file1500659334296 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:48:54 INFO - 1500659334297 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:48:54 INFO - 1500659334298 Marionette TRACE 384 <- [1,6,null,{}]
10:48:54 INFO - 1500659334300 Marionette DEBUG Closed connection 384
10:48:54 INFO - 1500659334301 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49954
10:48:54 INFO - 1500659334301 Marionette DEBUG Closed connection 385
10:48:54 INFO - 1500659334302 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49955
10:48:54 INFO - 1500659334303 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:54 INFO - 1500659334304 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334307 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334310 Marionette TRACE 386 <- [1,1,null,{"sessionId":"8b9adff3-d853-4fc1-89fe-9e56b20dc70e","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334312 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:48:54 INFO - 1500659334314 Marionette TRACE 386 <- [1,2,null,{}]
10:48:54 INFO - 1500659334315 Marionette DEBUG Closed connection 386
10:48:54 INFO - 1500659334316 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49956
10:48:54 INFO - 1500659334317 Marionette DEBUG Closed connection 387
10:48:54 INFO - 1500659334317 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49957
10:48:54 INFO - 1500659334319 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:48:54 INFO - 1500659334319 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334322 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334326 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9e19051f-de63-41f8-970d-7e671fe6e9e7","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334327 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:48:54 INFO - 1500659334329 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:54 INFO - 1500659334331 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:54 INFO - 1500659334331 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:48:54 INFO - 1500659334338 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:48:54 INFO - 1500659334344 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:48:54 INFO - 1500659334351 Marionette TRACE 388 <- [1,2,null,{}]
10:48:54 INFO - 1500659334355 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:48:54 INFO - 1500659334361 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"46448cb1-7c69-452f-98e0-39b3639f51ca","ELEMENT":"46448cb1-7c69-452f-98e0-39b3639f51ca"}}]
10:48:54 INFO - 1500659334363 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"46448cb1-7c69-452f-98e0-39b3639f51ca"}]
10:48:54 INFO - 1500659334399 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:48:54 INFO - 1500659334401 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
10:48:54 INFO - TEST-END: test_click.py TestClick.test_input_file1500659334408 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:48:54 INFO - 1500659334409 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:48:54 INFO - 1500659334410 Marionette TRACE 388 <- [1,6,null,{}]
10:48:54 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 136ms
10:48:54 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:48:54 INFO - 1500659334412 Marionette DEBUG Closed connection 388
10:48:54 INFO - 1500659334414 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49958
10:48:54 INFO - 1500659334414 Marionette DEBUG Closed connection 389
10:48:54 INFO - 1500659334415 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49959
10:48:54 INFO - 1500659334416 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:54 INFO - 1500659334417 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334420 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334423 Marionette TRACE 390 <- [1,1,null,{"sessionId":"bfcd00d1-b384-489b-bae3-57ca226f901f","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334425 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:48:54 INFO - 1500659334425 Marionette TRACE 390 <- [1,2,null,{}]
10:48:54 INFO - 1500659334426 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:48:54 INFO - 1500659334427 Marionette TRACE 390 <- [1,3,null,{}]
10:48:54 INFO - 1500659334428 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:48:54 INFO - 1500659334428 Marionette TRACE 390 <- [1,4,null,{}]
10:48:54 INFO - 1500659334430 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
10:48:54 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500659334433 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:48:54 INFO - 1500659334434 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:48:54 INFO - 1500659334435 Marionette TRACE 390 <- [1,6,null,{}]
10:48:54 INFO - 1500659334437 Marionette DEBUG Closed connection 390
10:48:54 INFO - 1500659334438 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49960
10:48:54 INFO - 1500659334439 Marionette DEBUG Closed connection 391
10:48:54 INFO - 1500659334439 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49961
10:48:54 INFO - 1500659334441 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:54 INFO - 1500659334441 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334444 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334447 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f4bab25f-4a5a-4cdc-b34e-e2ef827046d2","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334449 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:48:54 INFO - 1500659334450 Marionette TRACE 392 <- [1,2,null,{}]
10:48:54 INFO - 1500659334452 Marionette DEBUG Closed connection 392
10:48:54 INFO - 1500659334452 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49962
10:48:54 INFO - 1500659334453 Marionette DEBUG Closed connection 393
10:48:54 INFO - 1500659334454 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49963
10:48:54 INFO - 1500659334455 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:48:54 INFO - 1500659334456 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334458 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334462 Marionette TRACE 394 <- [1,1,null,{"sessionId":"95007c38-a675-4962-8ee6-5a16a6f811cf","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334463 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
10:48:54 INFO - 1500659334465 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:48:54 INFO - 1500659334467 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:48:54 INFO - 1500659334467 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:48:54 INFO - 1500659334477 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:48:54 INFO - 1500659334479 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:48:54 INFO - 1500659334487 Marionette TRACE 394 <- [1,2,null,{}]
10:48:54 INFO - 1500659334497 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:48:54 INFO - 1500659334501 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ff8ebf3-12a7-4d6d-807b-b7c692991b63","ELEMENT":"8ff8ebf3-12a7-4d6d-807b-b7c692991b63"}}]
10:48:54 INFO - 1500659334503 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:48:54 INFO - 1500659334507 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"724f1eda-665d-4535-8059-2e76133e03b5","ELEMENT":"724f1eda-665d-4535-8059-2e76133e03b5"}}]
10:48:54 INFO - 1500659334508 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"8ff8ebf3-12a7-4d6d-807b-b7c692991b63"}]
10:48:54 INFO - 1500659334762 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:48:54 INFO - 1500659334763 Marionette TRACE 394 <- [1,5,null,{}]
10:48:54 INFO - 1500659334764 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"724f1eda-665d-4535-8059-2e76133e03b5"}]
10:48:54 INFO - 1500659334783 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:48:54 INFO - 1500659334784 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
10:48:54 INFO - 1500659334788 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:48:54 INFO - 1500659334790 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
10:48:54 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500659334792 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:48:54 INFO - 1500659334793 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:48:54 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 383ms
10:48:54 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:48:54 INFO - 1500659334795 Marionette TRACE 394 <- [1,9,null,{}]
10:48:54 INFO - 1500659334797 Marionette DEBUG Closed connection 394
10:48:54 INFO - 1500659334798 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49964
10:48:54 INFO - 1500659334798 Marionette DEBUG Closed connection 395
10:48:54 INFO - 1500659334799 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49965
10:48:54 INFO - 1500659334800 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:54 INFO - 1500659334801 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334804 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334807 Marionette TRACE 396 <- [1,1,null,{"sessionId":"7eb3bd76-7b05-4fc0-8eed-4aa205a73b9b","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334809 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:48:54 INFO - 1500659334809 Marionette TRACE 396 <- [1,2,null,{}]
10:48:54 INFO - 1500659334810 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:48:54 INFO - 1500659334811 Marionette TRACE 396 <- [1,3,null,{}]
10:48:54 INFO - 1500659334812 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:48:54 INFO - 1500659334812 Marionette TRACE 396 <- [1,4,null,{}]
10:48:54 INFO - 1500659334814 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
10:48:54 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500659334817 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:48:54 INFO - 1500659334818 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:48:54 INFO - 1500659334819 Marionette TRACE 396 <- [1,6,null,{}]
10:48:54 INFO - 1500659334821 Marionette DEBUG Closed connection 396
10:48:54 INFO - 1500659334821 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49966
10:48:54 INFO - 1500659334824 Marionette DEBUG Closed connection 397
10:48:54 INFO - 1500659334824 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49967
10:48:54 INFO - 1500659334825 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:48:54 INFO - 1500659334826 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334829 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334833 Marionette TRACE 398 <- [1,1,null,{"sessionId":"f06c4f1e-8539-4163-9422-f5288deda8a9","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334834 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:48:54 INFO - 1500659334835 Marionette TRACE 398 <- [1,2,null,{}]
10:48:54 INFO - 1500659334837 Marionette DEBUG Closed connection 398
10:48:54 INFO - 1500659334837 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49968
10:48:54 INFO - 1500659334838 Marionette DEBUG Closed connection 399
10:48:54 INFO - 1500659334839 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49969
10:48:54 INFO - 1500659334840 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:48:54 INFO - 1500659334841 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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}
10:48:54 INFO - 1500659334844 Marionette DEBUG Register listener.js for window 6442450945
10:48:54 INFO - 1500659334848 Marionette TRACE 400 <- [1,1,null,{"sessionId":"35be06e8-f776-4187-b833-ac01a91ab85d","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":5564,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptsrjtv.mozrunner","moz:accessibilityChecks":false}}]
10:48:54 INFO - 1500659334849 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
10:48:54 INFO - 1500659334851 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:48:54 INFO - 1500659334853 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:48:54 INFO - 1500659334854 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:48:54 INFO - 1500659334861 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
10:48:54 INFO - 1500659334863 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
10:48:54 INFO - 1500659334869 Marionette TRACE 400 <- [1,2,null,{}]
10:48:54 INFO - 1500659334882 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:48:54 INFO - 1500659334885 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"00e3c45f-1987-4d88-8588-8db6f0bd72b8","ELEMENT":"00e3c45f-1987-4d88-8588-8db6f0bd72b8"}}]
10:48:54 INFO - 1500659334886 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"00e3c45f-1987-4d88-8588-8db6f0bd72b8"}]
10:48:54 INFO - 1500659334909 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:48:54 INFO - 1500659334910 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"00e3c45f-1987-4d88-8588-8db6f0bd72b8"}]
10:48:54 INFO - 1500659334928 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element