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]
18:30:17 INFO - 1500341417570 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}]
18:30:17 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500341417575 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:30:17 INFO - 1500341417576 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:30:17 INFO - 1500341417578 Marionette TRACE 352 <- [1,6,null,{}]
18:30:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 178ms
18:30:17 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:30:17 INFO - 1500341417580 Marionette DEBUG Closed connection 352
18:30:17 INFO - 1500341417582 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49864
18:30:17 INFO - 1500341417582 Marionette DEBUG Closed connection 353
18:30:17 INFO - 1500341417583 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49865
18:30:17 INFO - 1500341417584 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:17 INFO - 1500341417585 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:17 INFO - 1500341417588 Marionette DEBUG Register listener.js for window 15
18:30:17 INFO - 1500341417591 Marionette TRACE 354 <- [1,1,null,{"sessionId":"013bc72d-380c-47d7-9779-f37f1eab8092","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:17 INFO - 1500341417594 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:30:17 INFO - 1500341417594 Marionette TRACE 354 <- [1,2,null,{}]
18:30:17 INFO - 1500341417595 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:30:17 INFO - 1500341417596 Marionette TRACE 354 <- [1,3,null,{}]
18:30:17 INFO - 1500341417597 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:30:17 INFO - 1500341417598 Marionette TRACE 354 <- [1,4,null,{}]
18:30:17 INFO - 1500341417599 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}]
18:30:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500341417603 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:30:17 INFO - 1500341417604 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:30:17 INFO - 1500341417605 Marionette TRACE 354 <- [1,6,null,{}]
18:30:17 INFO - 1500341417608 Marionette DEBUG Closed connection 354
18:30:17 INFO - 1500341417608 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49866
18:30:17 INFO - 1500341417609 Marionette DEBUG Closed connection 355
18:30:17 INFO - 1500341417611 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49867
18:30:17 INFO - 1500341417612 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:17 INFO - 1500341417613 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:17 INFO - 1500341417623 Marionette DEBUG Register listener.js for window 15
18:30:17 INFO - 1500341417628 Marionette TRACE 356 <- [1,1,null,{"sessionId":"02818aa2-e5de-4fe5-9c14-71c0bb7e696a","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:17 INFO - 1500341417630 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:30:17 INFO - 1500341417632 Marionette TRACE 356 <- [1,2,null,{}]
18:30:17 INFO - 1500341417634 Marionette DEBUG Closed connection 356
18:30:17 INFO - 1500341417635 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49868
18:30:17 INFO - 1500341417636 Marionette DEBUG Closed connection 357
18:30:17 INFO - 1500341417637 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49869
18:30:17 INFO - 1500341417638 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:30:17 INFO - 1500341417638 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:17 INFO - 1500341417641 Marionette DEBUG Register listener.js for window 15
18:30:17 INFO - 1500341417645 Marionette TRACE 358 <- [1,1,null,{"sessionId":"1fad0a50-0fea-4fa0-b14b-e6f7abd73d6a","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:17 INFO - 1500341417647 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49427/clicks.html"}]
18:30:17 INFO - 1500341417649 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49427/hidden.html"
18:30:17 INFO - 1500341417655 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49427/hidden.html"
18:30:17 INFO - 1500341417656 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49427/hidden.html"
18:30:17 INFO - 1500341417676 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49427/clicks.html"
18:30:17 INFO - 1500341417685 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49427/clicks.html"
18:30:17 INFO - 1500341417690 Marionette TRACE 358 <- [1,2,null,{}]
18:30:17 INFO - 1500341417691 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:30:17 INFO - 1500341417702 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b47e9fdb-a9f1-4828-b9c8-c8c5fcd054f6","ELEMENT":"b47e9fdb-a9f1-4828-b9c8-c8c5fcd054f6"}}]
18:30:17 INFO - 1500341417703 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"b47e9fdb-a9f1-4828-b9c8-c8c5fcd054f6"}]
18:30:17 INFO - 1500341417750 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49427/clicks.html"
18:30:17 INFO - 1500341417758 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49427/clicks.html"
18:30:17 INFO - 1500341417759 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49427/clicks.html"
18:30:17 INFO - 1500341417796 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49427/test.html"
18:30:17 INFO - 1500341417801 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49427/test.html"
18:30:17 INFO - 1500341417814 Marionette TRACE 358 <- [1,4,null,{}]
18:30:17 INFO - 1500341417820 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:30:17 INFO - 1500341417823 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb730f55-3f29-4dad-9b3f-4ac5ec61cfdf","ELEMENT":"fb730f55-3f29-4dad-9b3f-4ac5ec61cfdf"}}]
18:30:17 INFO - 1500341417824 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:30:17 INFO - 1500341417824 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:30:17 INFO - 1500341417826 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}]
18:30:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500341417829 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:30:17 INFO - 1500341417832 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:30:17 INFO - 1500341417834 Marionette TRACE 358 <- [1,8,null,{}]
18:30:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 255ms
18:30:17 INFO - TEST-START | test_click.py TestClick.test_container_element
18:30:17 INFO - 1500341417837 Marionette DEBUG Closed connection 358
18:30:17 INFO - 1500341417838 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49872
18:30:17 INFO - 1500341417862 Marionette DEBUG Closed connection 359
18:30:17 INFO - 1500341417862 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49873
18:30:17 INFO - 1500341417868 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:17 INFO - 1500341417869 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:17 INFO - 1500341417872 Marionette DEBUG Register listener.js for window 15
18:30:17 INFO - 1500341417875 Marionette TRACE 360 <- [1,1,null,{"sessionId":"c4449a0d-62ff-4ebb-88db-c73faaaefdda","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:17 INFO - 1500341417879 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:30:17 INFO - 1500341417880 Marionette TRACE 360 <- [1,2,null,{}]
18:30:17 INFO - 1500341417882 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:30:17 INFO - 1500341417883 Marionette TRACE 360 <- [1,3,null,{}]
18:30:17 INFO - 1500341417884 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:30:17 INFO - 1500341417884 Marionette TRACE 360 <- [1,4,null,{}]
18:30:17 INFO - 1500341417891 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}]
18:30:17 INFO - TEST-START: test_click.py TestClick.test_container_element1500341417894 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:30:17 INFO - 1500341417895 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:30:17 INFO - 1500341417897 Marionette TRACE 360 <- [1,6,null,{}]
18:30:17 INFO - 1500341417900 Marionette DEBUG Closed connection 360
18:30:17 INFO - 1500341417901 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49874
18:30:17 INFO - 1500341417902 Marionette DEBUG Closed connection 361
18:30:17 INFO - 1500341417903 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49875
18:30:17 INFO - 1500341417904 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:17 INFO - 1500341417905 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:17 INFO - 1500341417907 Marionette DEBUG Register listener.js for window 15
18:30:17 INFO - 1500341417911 Marionette TRACE 362 <- [1,1,null,{"sessionId":"469feba4-2802-4509-b184-44f8fbefdd4d","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:17 INFO - 1500341417915 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:30:17 INFO - 1500341417917 Marionette TRACE 362 <- [1,2,null,{}]
18:30:17 INFO - 1500341417919 Marionette DEBUG Closed connection 362
18:30:17 INFO - 1500341417920 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49876
18:30:17 INFO - 1500341417922 Marionette DEBUG Closed connection 363
18:30:17 INFO - 1500341417923 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49877
18:30:17 INFO - 1500341417924 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:30:17 INFO - 1500341417925 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:17 INFO - 1500341417928 Marionette DEBUG Register listener.js for window 15
18:30:17 INFO - 1500341417932 Marionette TRACE 364 <- [1,1,null,{"sessionId":"cae90a3f-f8a7-46cf-9ff6-bb301e6accaf","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:17 INFO - 1500341417934 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"}]
18:30:17 INFO - 1500341417936 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49427/test.html"
18:30:17 INFO - 1500341417938 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49427/test.html"
18:30:17 INFO - 1500341417939 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49427/test.html"
18:30:17 INFO - 1500341417965 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"
18:30:17 INFO - 1500341417971 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"
18:30:17 INFO - 1500341417975 Marionette TRACE 364 <- [1,2,null,{}]
18:30:17 INFO - 1500341417976 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:30:17 INFO - 1500341417987 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8c1c20cf-9d04-421b-8aa6-b07b933efd3f","ELEMENT":"8c1c20cf-9d04-421b-8aa6-b07b933efd3f"}}]
18:30:17 INFO - 1500341417990 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8c1c20cf-9d04-421b-8aa6-b07b933efd3f"}]
18:30:18 INFO - 1500341418234 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:30:18 INFO - 1500341418235 Marionette TRACE 364 <- [1,4,null,{}]
18:30:18 INFO - 1500341418237 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8c1c20cf-9d04-421b-8aa6-b07b933efd3f","name":"selected"}]
18:30:18 INFO - 1500341418241 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:30:18 INFO - 1500341418243 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}]
18:30:18 INFO - TEST-END: test_click.py TestClick.test_container_element1500341418246 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:30:18 INFO - 1500341418247 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:30:18 INFO - 1500341418249 Marionette TRACE 364 <- [1,7,null,{}]
18:30:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 414ms
18:30:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:30:18 INFO - 1500341418251 Marionette DEBUG Closed connection 364
18:30:18 INFO - 1500341418252 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49878
18:30:18 INFO - 1500341418253 Marionette DEBUG Closed connection 365
18:30:18 INFO - 1500341418254 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49879
18:30:18 INFO - 1500341418255 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:18 INFO - 1500341418255 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:18 INFO - 1500341418258 Marionette DEBUG Register listener.js for window 15
18:30:18 INFO - 1500341418262 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e7cebc72-2a5d-47eb-824f-ccffd1b947f5","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:18 INFO - 1500341418264 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:30:18 INFO - 1500341418264 Marionette TRACE 366 <- [1,2,null,{}]
18:30:18 INFO - 1500341418265 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:30:18 INFO - 1500341418266 Marionette TRACE 366 <- [1,3,null,{}]
18:30:18 INFO - 1500341418267 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:30:18 INFO - 1500341418267 Marionette TRACE 366 <- [1,4,null,{}]
18:30:18 INFO - 1500341418269 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}]
18:30:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500341418272 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:30:18 INFO - 1500341418273 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:30:18 INFO - 1500341418275 Marionette TRACE 366 <- [1,6,null,{}]
18:30:18 INFO - 1500341418277 Marionette DEBUG Closed connection 366
18:30:18 INFO - 1500341418278 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49880
18:30:18 INFO - 1500341418279 Marionette DEBUG Closed connection 367
18:30:18 INFO - 1500341418279 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49881
18:30:18 INFO - 1500341418281 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:18 INFO - 1500341418281 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:18 INFO - 1500341418284 Marionette DEBUG Register listener.js for window 15
18:30:18 INFO - 1500341418295 Marionette TRACE 368 <- [1,1,null,{"sessionId":"4a09648d-efea-4e9e-be04-7d4548f13e0e","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:18 INFO - 1500341418298 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:30:18 INFO - 1500341418299 Marionette TRACE 368 <- [1,2,null,{}]
18:30:18 INFO - 1500341418301 Marionette DEBUG Closed connection 368
18:30:18 INFO - 1500341418302 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49882
18:30:18 INFO - 1500341418303 Marionette DEBUG Closed connection 369
18:30:18 INFO - 1500341418304 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49883
18:30:18 INFO - 1500341418305 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:30:18 INFO - 1500341418306 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:18 INFO - 1500341418309 Marionette DEBUG Register listener.js for window 15
18:30:18 INFO - 1500341418312 Marionette TRACE 370 <- [1,1,null,{"sessionId":"21239919-a789-4b7d-9ee1-0e58592a62f2","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:18 INFO - 1500341418314 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"}]
18:30:18 INFO - 1500341418317 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"
18:30:18 INFO - 1500341418320 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"
18:30:18 INFO - 1500341418321 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"
18:30:18 INFO - 1500341418341 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"
18:30:18 INFO - 1500341418345 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"
18:30:18 INFO - 1500341418360 Marionette TRACE 370 <- [1,2,null,{}]
18:30:18 INFO - 1500341418363 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:30:18 INFO - 1500341418366 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d93a527b-ee6d-4cc2-9fdd-4cbf21465810","ELEMENT":"d93a527b-ee6d-4cc2-9fdd-4cbf21465810"}}]
18:30:18 INFO - 1500341418369 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d93a527b-ee6d-4cc2-9fdd-4cbf21465810"}]
18:30:18 INFO - 1500341418616 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:30:18 INFO - 1500341418617 Marionette TRACE 370 <- [1,4,null,{}]
18:30:18 INFO - 1500341418619 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d93a527b-ee6d-4cc2-9fdd-4cbf21465810","name":"selected"}]
18:30:18 INFO - 1500341418624 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:30:18 INFO - 1500341418625 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}]
18:30:18 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500341418628 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:30:18 INFO - 1500341418688 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:30:18 INFO - 1500341418690 Marionette TRACE 370 <- [1,7,null,{}]
18:30:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 440ms
18:30:18 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:30:18 INFO - 1500341418704 Marionette DEBUG Closed connection 370
18:30:18 INFO - 1500341418707 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49884
18:30:18 INFO - 1500341418708 Marionette DEBUG Closed connection 371
18:30:18 INFO - 1500341418709 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49885
18:30:18 INFO - 1500341418710 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:18 INFO - 1500341418710 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:18 INFO - 1500341418713 Marionette DEBUG Register listener.js for window 15
18:30:18 INFO - 1500341418717 Marionette TRACE 372 <- [1,1,null,{"sessionId":"38fd627f-1c3f-4118-8158-440e301e4379","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:18 INFO - 1500341418719 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:30:18 INFO - 1500341418720 Marionette TRACE 372 <- [1,2,null,{}]
18:30:18 INFO - 1500341418722 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:30:18 INFO - 1500341418723 Marionette TRACE 372 <- [1,3,null,{}]
18:30:18 INFO - 1500341418724 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:30:18 INFO - 1500341418725 Marionette TRACE 372 <- [1,4,null,{}]
18:30:18 INFO - 1500341418727 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}]
18:30:18 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500341418730 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:30:18 INFO - 1500341418731 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:30:18 INFO - 1500341418732 Marionette TRACE 372 <- [1,6,null,{}]
18:30:18 INFO - 1500341418735 Marionette DEBUG Closed connection 372
18:30:18 INFO - 1500341418736 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49886
18:30:18 INFO - 1500341418736 Marionette DEBUG Closed connection 373
18:30:18 INFO - 1500341418737 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49887
18:30:18 INFO - 1500341418738 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:18 INFO - 1500341418739 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:18 INFO - 1500341418742 Marionette DEBUG Register listener.js for window 15
18:30:18 INFO - 1500341418745 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d849d848-20a8-48a8-9265-8c5c89224208","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:18 INFO - 1500341418748 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:30:18 INFO - 1500341418749 Marionette TRACE 374 <- [1,2,null,{}]
18:30:18 INFO - 1500341418751 Marionette DEBUG Closed connection 374
18:30:18 INFO - 1500341418752 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49888
18:30:18 INFO - 1500341418753 Marionette DEBUG Closed connection 375
18:30:18 INFO - 1500341418754 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49889
18:30:18 INFO - 1500341418755 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:30:18 INFO - 1500341418756 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:18 INFO - 1500341418758 Marionette DEBUG Register listener.js for window 15
18:30:18 INFO - 1500341418762 Marionette TRACE 376 <- [1,1,null,{"sessionId":"1f0fa50d-dba8-416f-b9cc-9c0e74f1140f","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:18 INFO - 1500341418765 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"}]
18:30:18 INFO - 1500341418767 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"
18:30:18 INFO - 1500341418769 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"
18:30:18 INFO - 1500341418770 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"
18:30:18 INFO - 1500341418790 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"
18:30:18 INFO - 1500341418794 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"
18:30:18 INFO - 1500341418806 Marionette TRACE 376 <- [1,2,null,{}]
18:30:18 INFO - 1500341418808 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:30:18 INFO - 1500341418811 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f37424c6-cc45-4c55-9f71-b64ce014684b","ELEMENT":"f37424c6-cc45-4c55-9f71-b64ce014684b"}}]
18:30:18 INFO - 1500341418813 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f37424c6-cc45-4c55-9f71-b64ce014684b"}]
18:30:19 INFO - 1500341419055 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:30:19 INFO - 1500341419057 Marionette TRACE 376 <- [1,4,null,{}]
18:30:19 INFO - 1500341419059 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}]
18:30:19 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500341419062 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:30:19 INFO - 1500341419063 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:30:19 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 374ms
18:30:19 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:30:19 INFO - 1500341419065 Marionette TRACE 376 <- [1,6,null,{}]
18:30:19 INFO - 1500341419067 Marionette DEBUG Closed connection 376
18:30:19 INFO - 1500341419068 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49890
18:30:19 INFO - 1500341419069 Marionette DEBUG Closed connection 377
18:30:19 INFO - 1500341419069 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49891
18:30:19 INFO - 1500341419070 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:19 INFO - 1500341419071 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419074 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419078 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c12fcdb3-29b8-4ea4-ad30-d0b7bd6007f6","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419080 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:30:19 INFO - 1500341419081 Marionette TRACE 378 <- [1,2,null,{}]
18:30:19 INFO - 1500341419082 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:30:19 INFO - 1500341419082 Marionette TRACE 378 <- [1,3,null,{}]
18:30:19 INFO - 1500341419083 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:30:19 INFO - 1500341419084 Marionette TRACE 378 <- [1,4,null,{}]
18:30:19 INFO - 1500341419086 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}]
18:30:19 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500341419089 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:30:19 INFO - 1500341419090 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:30:19 INFO - 1500341419091 Marionette TRACE 378 <- [1,6,null,{}]
18:30:19 INFO - 1500341419093 Marionette DEBUG Closed connection 378
18:30:19 INFO - 1500341419094 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49892
18:30:19 INFO - 1500341419095 Marionette DEBUG Closed connection 379
18:30:19 INFO - 1500341419096 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49893
18:30:19 INFO - 1500341419097 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:19 INFO - 1500341419097 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419100 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419104 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d712e691-1ea5-43fc-9920-aeee96a98d60","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419114 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:30:19 INFO - 1500341419115 Marionette TRACE 380 <- [1,2,null,{}]
18:30:19 INFO - 1500341419118 Marionette DEBUG Closed connection 380
18:30:19 INFO - 1500341419119 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49894
18:30:19 INFO - 1500341419119 Marionette DEBUG Closed connection 381
18:30:19 INFO - 1500341419120 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49895
18:30:19 INFO - 1500341419121 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:30:19 INFO - 1500341419122 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419125 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419128 Marionette TRACE 382 <- [1,1,null,{"sessionId":"063471c1-0d69-4682-86b6-acc3700f9a9f","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419131 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"}]
18:30:19 INFO - 1500341419133 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"
18:30:19 INFO - 1500341419136 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"
18:30:19 INFO - 1500341419136 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"
18:30:19 INFO - 1500341419156 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"
18:30:19 INFO - 1500341419160 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"
18:30:19 INFO - 1500341419171 Marionette TRACE 382 <- [1,2,null,{}]
18:30:19 INFO - 1500341419173 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:30:19 INFO - 1500341419176 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2db65c67-103b-48ec-a471-32d6905932c5","ELEMENT":"2db65c67-103b-48ec-a471-32d6905932c5"}}]
18:30:19 INFO - 1500341419177 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"2db65c67-103b-48ec-a471-32d6905932c5"}]
18:30:19 INFO - 1500341419424 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:30:19 INFO - 1500341419425 Marionette TRACE 382 <- [1,4,null,{}]
18:30:19 INFO - 1500341419428 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}]
18:30:19 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500341419431 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:30:19 INFO - 1500341419432 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:30:19 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 369ms
18:30:19 INFO - TEST-START | test_click.py TestClick.test_input_file
18:30:19 INFO - 1500341419434 Marionette TRACE 382 <- [1,6,null,{}]
18:30:19 INFO - 1500341419436 Marionette DEBUG Closed connection 382
18:30:19 INFO - 1500341419437 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49896
18:30:19 INFO - 1500341419438 Marionette DEBUG Closed connection 383
18:30:19 INFO - 1500341419438 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49897
18:30:19 INFO - 1500341419440 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:19 INFO - 1500341419440 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419443 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419446 Marionette TRACE 384 <- [1,1,null,{"sessionId":"8ba605cb-0587-49bd-8b78-0c03dcf343b2","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419449 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:30:19 INFO - 1500341419449 Marionette TRACE 384 <- [1,2,null,{}]
18:30:19 INFO - 1500341419450 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:30:19 INFO - 1500341419451 Marionette TRACE 384 <- [1,3,null,{}]
18:30:19 INFO - 1500341419452 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:30:19 INFO - 1500341419452 Marionette TRACE 384 <- [1,4,null,{}]
18:30:19 INFO - 1500341419454 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}]
18:30:19 INFO - TEST-START: test_click.py TestClick.test_input_file1500341419457 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:30:19 INFO - 1500341419458 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:30:19 INFO - 1500341419459 Marionette TRACE 384 <- [1,6,null,{}]
18:30:19 INFO - 1500341419461 Marionette DEBUG Closed connection 384
18:30:19 INFO - 1500341419462 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49898
18:30:19 INFO - 1500341419463 Marionette DEBUG Closed connection 385
18:30:19 INFO - 1500341419464 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49899
18:30:19 INFO - 1500341419465 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:19 INFO - 1500341419465 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419468 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419471 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5da520df-2a29-4e2d-8fe8-33ef418d15c8","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419473 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:30:19 INFO - 1500341419475 Marionette TRACE 386 <- [1,2,null,{}]
18:30:19 INFO - 1500341419477 Marionette DEBUG Closed connection 386
18:30:19 INFO - 1500341419478 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49900
18:30:19 INFO - 1500341419479 Marionette DEBUG Closed connection 387
18:30:19 INFO - 1500341419479 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49901
18:30:19 INFO - 1500341419480 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:30:19 INFO - 1500341419481 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419484 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419487 Marionette TRACE 388 <- [1,1,null,{"sessionId":"fb5c5b0c-9dc0-4308-93ef-d682698866e8","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419489 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:30:19 INFO - 1500341419491 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"
18:30:19 INFO - 1500341419493 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"
18:30:19 INFO - 1500341419494 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"
18:30:19 INFO - 1500341419516 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:30:19 INFO - 1500341419520 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:30:19 INFO - 1500341419524 Marionette TRACE 388 <- [1,2,null,{}]
18:30:19 INFO - 1500341419525 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:30:19 INFO - 1500341419536 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6637cdd3-14c1-4bf9-9486-a0ed1e1b09fa","ELEMENT":"6637cdd3-14c1-4bf9-9486-a0ed1e1b09fa"}}]
18:30:19 INFO - 1500341419538 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6637cdd3-14c1-4bf9-9486-a0ed1e1b09fa"}]
18:30:19 INFO - 1500341419568 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]
18:30:19 INFO - 1500341419570 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}]
18:30:19 INFO - TEST-END: test_click.py TestClick.test_input_file1500341419573 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:30:19 INFO - 1500341419574 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:30:19 INFO - 1500341419576 Marionette TRACE 388 <- [1,6,null,{}]
18:30:19 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 142ms
18:30:19 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:30:19 INFO - 1500341419579 Marionette DEBUG Closed connection 388
18:30:19 INFO - 1500341419580 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49902
18:30:19 INFO - 1500341419580 Marionette DEBUG Closed connection 389
18:30:19 INFO - 1500341419581 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49903
18:30:19 INFO - 1500341419582 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:19 INFO - 1500341419583 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419586 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419590 Marionette TRACE 390 <- [1,1,null,{"sessionId":"33810448-5d9d-4c48-a1a0-ca4d735ea16d","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419592 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:30:19 INFO - 1500341419593 Marionette TRACE 390 <- [1,2,null,{}]
18:30:19 INFO - 1500341419594 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:30:19 INFO - 1500341419594 Marionette TRACE 390 <- [1,3,null,{}]
18:30:19 INFO - 1500341419595 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:30:19 INFO - 1500341419596 Marionette TRACE 390 <- [1,4,null,{}]
18:30:19 INFO - 1500341419598 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}]
18:30:19 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500341419601 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:30:19 INFO - 1500341419602 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:30:19 INFO - 1500341419603 Marionette TRACE 390 <- [1,6,null,{}]
18:30:19 INFO - 1500341419606 Marionette DEBUG Closed connection 390
18:30:19 INFO - 1500341419606 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49904
18:30:19 INFO - 1500341419607 Marionette DEBUG Closed connection 391
18:30:19 INFO - 1500341419608 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49905
18:30:19 INFO - 1500341419609 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:19 INFO - 1500341419610 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419613 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419616 Marionette TRACE 392 <- [1,1,null,{"sessionId":"58c27263-9651-4b75-8619-efda48024483","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419618 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:30:19 INFO - 1500341419619 Marionette TRACE 392 <- [1,2,null,{}]
18:30:19 INFO - 1500341419622 Marionette DEBUG Closed connection 392
18:30:19 INFO - 1500341419623 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49906
18:30:19 INFO - 1500341419623 Marionette DEBUG Closed connection 393
18:30:19 INFO - 1500341419624 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49907
18:30:19 INFO - 1500341419625 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:30:19 INFO - 1500341419626 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419629 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419640 Marionette TRACE 394 <- [1,1,null,{"sessionId":"b0bf0d7b-d3d9-488e-8f51-5aad0c17706e","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419643 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"}]
18:30:19 INFO - 1500341419644 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:30:19 INFO - 1500341419647 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:30:19 INFO - 1500341419648 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:30:19 INFO - 1500341419672 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"
18:30:19 INFO - 1500341419674 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"
18:30:19 INFO - 1500341419678 Marionette TRACE 394 <- [1,2,null,{}]
18:30:19 INFO - 1500341419687 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:30:19 INFO - 1500341419690 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb14551f-6e62-4ea5-987c-86a7aa1aa55e","ELEMENT":"fb14551f-6e62-4ea5-987c-86a7aa1aa55e"}}]
18:30:19 INFO - 1500341419691 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:30:19 INFO - 1500341419694 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"49b016b6-8f6b-4de6-af8d-8e5156d1ae75","ELEMENT":"49b016b6-8f6b-4de6-af8d-8e5156d1ae75"}}]
18:30:19 INFO - 1500341419696 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"fb14551f-6e62-4ea5-987c-86a7aa1aa55e"}]
18:30:19 INFO - 1500341419936 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:30:19 INFO - 1500341419937 Marionette TRACE 394 <- [1,5,null,{}]
18:30:19 INFO - 1500341419939 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"49b016b6-8f6b-4de6-af8d-8e5156d1ae75"}]
18:30:19 INFO - 1500341419956 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]
18:30:19 INFO - 1500341419958 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:30:19 INFO - 1500341419961 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:30:19 INFO - 1500341419963 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}]
18:30:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500341419966 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:30:19 INFO - 1500341419967 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:30:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 392ms
18:30:19 INFO - 1500341419969 Marionette TRACE 394 <- [1,9,null,{}]
18:30:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:30:19 INFO - 1500341419971 Marionette DEBUG Closed connection 394
18:30:19 INFO - 1500341419972 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49908
18:30:19 INFO - 1500341419973 Marionette DEBUG Closed connection 395
18:30:19 INFO - 1500341419974 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49909
18:30:19 INFO - 1500341419975 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:19 INFO - 1500341419976 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:19 INFO - 1500341419979 Marionette DEBUG Register listener.js for window 15
18:30:19 INFO - 1500341419983 Marionette TRACE 396 <- [1,1,null,{"sessionId":"eca8538b-e8d6-48de-9672-dbccbc7f9f5f","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:19 INFO - 1500341419985 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:30:19 INFO - 1500341419985 Marionette TRACE 396 <- [1,2,null,{}]
18:30:19 INFO - 1500341419986 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:30:19 INFO - 1500341419987 Marionette TRACE 396 <- [1,3,null,{}]
18:30:19 INFO - 1500341419987 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:30:19 INFO - 1500341419988 Marionette TRACE 396 <- [1,4,null,{}]
18:30:19 INFO - 1500341419990 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}]
18:30:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500341419993 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:30:19 INFO - 1500341419994 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:30:19 INFO - 1500341419995 Marionette TRACE 396 <- [1,6,null,{}]
18:30:19 INFO - 1500341419998 Marionette DEBUG Closed connection 396
18:30:20 INFO - 1500341419998 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49910
18:30:20 INFO - 1500341419999 Marionette DEBUG Closed connection 397
18:30:20 INFO - 1500341420000 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49911
18:30:20 INFO - 1500341420001 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:30:20 INFO - 1500341420002 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:20 INFO - 1500341420004 Marionette DEBUG Register listener.js for window 15
18:30:20 INFO - 1500341420008 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1e419e58-cc27-489d-95a5-03292bd2c6b3","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:20 INFO - 1500341420010 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:30:20 INFO - 1500341420011 Marionette TRACE 398 <- [1,2,null,{}]
18:30:20 INFO - 1500341420014 Marionette DEBUG Closed connection 398
18:30:20 INFO - 1500341420015 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49912
18:30:20 INFO - 1500341420015 Marionette DEBUG Closed connection 399
18:30:20 INFO - 1500341420016 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49913
18:30:20 INFO - 1500341420017 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:30:20 INFO - 1500341420018 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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}
18:30:20 INFO - 1500341420021 Marionette DEBUG Register listener.js for window 15
18:30:20 INFO - 1500341420026 Marionette TRACE 400 <- [1,1,null,{"sessionId":"aae45dbf-c87a-4bc9-b311-a13800c2eadf","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":3944,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp0jk11r.mozrunner","moz:accessibilityChecks":false}}]
18:30:20 INFO - 1500341420028 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"}]
18:30:20 INFO - 1500341420030 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"
18:30:20 INFO - 1500341420033 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"
18:30:20 INFO - 1500341420033 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"
18:30:20 INFO - 1500341420057 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"
18:30:20 INFO - 1500341420059 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"
18:30:20 INFO - 1500341420070 Marionette TRACE 400 <- [1,2,null,{}]
18:30:20 INFO - 1500341420072 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:30:20 INFO - 1500341420074 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"01a1145c-ce6a-4d65-a5eb-ae551af799d2","ELEMENT":"01a1145c-ce6a-4d65-a5eb-ae551af799d2"}}]
18:30:20 INFO - 1500341420075 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"01a1145c-ce6a-4d65-a5eb-ae551af799d2"}]
18:30:20 INFO - 1500341420100 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:30:20 INFO - 1500341420102 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"01a1145c-ce6a-4d65-a5eb-ae551af799d2"}]
18:30:20 INFO - 1500341420124 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element