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]
21:39:19 INFO - 1500439159724 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
21:39:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500439159726 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:39:19 INFO - 1500439159727 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:39:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 126ms
21:39:19 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:39:19 INFO - 1500439159728 Marionette TRACE 352 <- [1,6,null,{}]
21:39:19 INFO - 1500439159730 Marionette DEBUG Closed connection 352
21:39:19 INFO - 1500439159731 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49838
21:39:19 INFO - 1500439159732 Marionette DEBUG Closed connection 353
21:39:19 INFO - 1500439159733 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49839
21:39:19 INFO - 1500439159734 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:19 INFO - 1500439159734 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:19 INFO - 1500439159736 Marionette DEBUG Register listener.js for window 15
21:39:19 INFO - 1500439159748 Marionette TRACE 354 <- [1,1,null,{"sessionId":"11831e07-021e-4acd-87ad-61f07d3842c1","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:19 INFO - 1500439159751 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:39:19 INFO - 1500439159751 Marionette TRACE 354 <- [1,2,null,{}]
21:39:19 INFO - 1500439159752 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:19 INFO - 1500439159752 Marionette TRACE 354 <- [1,3,null,{}]
21:39:19 INFO - 1500439159753 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:19 INFO - 1500439159754 Marionette TRACE 354 <- [1,4,null,{}]
21:39:19 INFO - 1500439159756 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
21:39:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500439159758 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:39:19 INFO - 1500439159759 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:39:19 INFO - 1500439159761 Marionette TRACE 354 <- [1,6,null,{}]
21:39:19 INFO - 1500439159762 Marionette DEBUG Closed connection 354
21:39:19 INFO - 1500439159763 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49840
21:39:19 INFO - 1500439159764 Marionette DEBUG Closed connection 355
21:39:19 INFO - 1500439159765 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49841
21:39:19 INFO - 1500439159765 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:19 INFO - 1500439159766 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:19 INFO - 1500439159768 Marionette DEBUG Register listener.js for window 15
21:39:19 INFO - 1500439159770 Marionette TRACE 356 <- [1,1,null,{"sessionId":"8b1e34f8-e981-4fcc-860e-3b26e52e7b7c","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:19 INFO - 1500439159772 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:39:19 INFO - 1500439159773 Marionette TRACE 356 <- [1,2,null,{}]
21:39:19 INFO - 1500439159775 Marionette DEBUG Closed connection 356
21:39:19 INFO - 1500439159776 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49842
21:39:19 INFO - 1500439159776 Marionette DEBUG Closed connection 357
21:39:19 INFO - 1500439159777 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49843
21:39:19 INFO - 1500439159778 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:19 INFO - 1500439159779 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:19 INFO - 1500439159780 Marionette DEBUG Register listener.js for window 15
21:39:19 INFO - 1500439159783 Marionette TRACE 358 <- [1,1,null,{"sessionId":"449a38a6-eee7-4c29-a1e1-3c70fecd9bbd","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:19 INFO - 1500439159785 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49406/clicks.html"}]
21:39:19 INFO - 1500439159786 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49406/hidden.html"
21:39:19 INFO - 1500439159795 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49406/hidden.html"
21:39:19 INFO - 1500439159795 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49406/hidden.html"
21:39:19 INFO - 1500439159808 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49406/clicks.html"
21:39:19 INFO - 1500439159815 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49406/clicks.html"
21:39:19 INFO - 1500439159818 Marionette TRACE 358 <- [1,2,null,{}]
21:39:19 INFO - 1500439159819 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:39:19 INFO - 1500439159822 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0d143936-73c4-4534-874a-9d6c1cca37b9","ELEMENT":"0d143936-73c4-4534-874a-9d6c1cca37b9"}}]
21:39:19 INFO - 1500439159823 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0d143936-73c4-4534-874a-9d6c1cca37b9"}]
21:39:19 INFO - 1500439159863 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49406/clicks.html"
21:39:19 INFO - 1500439159868 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49406/clicks.html"
21:39:19 INFO - 1500439159869 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49406/clicks.html"
21:39:19 INFO - 1500439159888 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49406/test.html"
21:39:19 INFO - 1500439159890 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49406/test.html"
21:39:19 INFO - 1500439159900 Marionette TRACE 358 <- [1,4,null,{}]
21:39:19 INFO - 1500439159902 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:39:19 INFO - 1500439159905 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9bf8a473-e165-4ff2-a8c3-4ea01516b5be","ELEMENT":"9bf8a473-e165-4ff2-a8c3-4ea01516b5be"}}]
21:39:19 INFO - 1500439159906 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:39:19 INFO - 1500439159907 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:39:19 INFO - 1500439159909 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
21:39:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500439159912 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:39:19 INFO - 1500439159913 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:39:19 INFO - 1500439159914 Marionette TRACE 358 <- [1,8,null,{}]
21:39:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 185ms
21:39:19 INFO - TEST-START | test_click.py TestClick.test_container_element
21:39:19 INFO - 1500439159916 Marionette DEBUG Closed connection 358
21:39:19 INFO - 1500439159917 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49846
21:39:19 INFO - 1500439159918 Marionette DEBUG Closed connection 359
21:39:19 INFO - 1500439159918 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49847
21:39:19 INFO - 1500439159919 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:19 INFO - 1500439159920 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:19 INFO - 1500439159922 Marionette DEBUG Register listener.js for window 15
21:39:19 INFO - 1500439159924 Marionette TRACE 360 <- [1,1,null,{"sessionId":"6d01a5aa-dc48-4042-870a-0fb925c41e1f","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:19 INFO - 1500439159926 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:39:19 INFO - 1500439159927 Marionette TRACE 360 <- [1,2,null,{}]
21:39:19 INFO - 1500439159928 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:19 INFO - 1500439159928 Marionette TRACE 360 <- [1,3,null,{}]
21:39:19 INFO - 1500439159929 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:19 INFO - 1500439159929 Marionette TRACE 360 <- [1,4,null,{}]
21:39:19 INFO - 1500439159931 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
21:39:19 INFO - TEST-START: test_click.py TestClick.test_container_element1500439159933 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:39:19 INFO - 1500439159934 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:39:19 INFO - 1500439159935 Marionette TRACE 360 <- [1,6,null,{}]
21:39:19 INFO - 1500439159937 Marionette DEBUG Closed connection 360
21:39:19 INFO - 1500439159938 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49848
21:39:19 INFO - 1500439159938 Marionette DEBUG Closed connection 361
21:39:19 INFO - 1500439159939 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49849
21:39:19 INFO - 1500439159940 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:19 INFO - 1500439159940 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:19 INFO - 1500439159942 Marionette DEBUG Register listener.js for window 15
21:39:19 INFO - 1500439159945 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1c96a63b-487b-44f4-85d8-1d426d88b977","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:19 INFO - 1500439159947 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:39:19 INFO - 1500439159948 Marionette TRACE 362 <- [1,2,null,{}]
21:39:19 INFO - 1500439159949 Marionette DEBUG Closed connection 362
21:39:19 INFO - 1500439159950 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49850
21:39:19 INFO - 1500439159951 Marionette DEBUG Closed connection 363
21:39:19 INFO - 1500439159952 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49851
21:39:19 INFO - 1500439159953 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:19 INFO - 1500439159953 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:19 INFO - 1500439159955 Marionette DEBUG Register listener.js for window 15
21:39:19 INFO - 1500439159958 Marionette TRACE 364 <- [1,1,null,{"sessionId":"8db13a0d-f7d7-4b67-8dad-1adb7d2ec28e","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:19 INFO - 1500439159960 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:39:19 INFO - 1500439159961 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49406/test.html"
21:39:19 INFO - 1500439159964 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49406/test.html"
21:39:19 INFO - 1500439159964 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49406/test.html"
21:39:20 INFO - 1500439159995 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439159998 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160003 Marionette TRACE 364 <- [1,2,null,{}]
21:39:20 INFO - 1500439160004 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:39:20 INFO - 1500439160006 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"942308e7-b488-4847-82bc-99889d7da755","ELEMENT":"942308e7-b488-4847-82bc-99889d7da755"}}]
21:39:20 INFO - 1500439160013 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"942308e7-b488-4847-82bc-99889d7da755"}]
21:39:20 INFO - 1500439160253 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:20 INFO - 1500439160254 Marionette TRACE 364 <- [1,4,null,{}]
21:39:20 INFO - 1500439160257 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"942308e7-b488-4847-82bc-99889d7da755","name":"selected"}]
21:39:20 INFO - 1500439160262 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:39:20 INFO - 1500439160268 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
21:39:20 INFO - TEST-END: test_click.py TestClick.test_container_element1500439160271 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:39:20 INFO - 1500439160272 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:39:20 INFO - 1500439160273 Marionette TRACE 364 <- [1,7,null,{}]
21:39:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 359ms
21:39:20 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:39:20 INFO - 1500439160275 Marionette DEBUG Closed connection 364
21:39:20 INFO - 1500439160276 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49852
21:39:20 INFO - 1500439160277 Marionette DEBUG Closed connection 365
21:39:20 INFO - 1500439160277 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49853
21:39:20 INFO - 1500439160278 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:20 INFO - 1500439160279 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:20 INFO - 1500439160281 Marionette DEBUG Register listener.js for window 15
21:39:20 INFO - 1500439160284 Marionette TRACE 366 <- [1,1,null,{"sessionId":"48d1f190-1381-4ff7-9648-5d3cb1c15248","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:20 INFO - 1500439160295 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:39:20 INFO - 1500439160296 Marionette TRACE 366 <- [1,2,null,{}]
21:39:20 INFO - 1500439160297 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:20 INFO - 1500439160297 Marionette TRACE 366 <- [1,3,null,{}]
21:39:20 INFO - 1500439160298 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:20 INFO - 1500439160298 Marionette TRACE 366 <- [1,4,null,{}]
21:39:20 INFO - 1500439160300 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
21:39:20 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500439160303 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:39:20 INFO - 1500439160304 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:39:20 INFO - 1500439160305 Marionette TRACE 366 <- [1,6,null,{}]
21:39:20 INFO - 1500439160307 Marionette DEBUG Closed connection 366
21:39:20 INFO - 1500439160308 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49854
21:39:20 INFO - 1500439160308 Marionette DEBUG Closed connection 367
21:39:20 INFO - 1500439160309 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49855
21:39:20 INFO - 1500439160310 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:20 INFO - 1500439160311 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:20 INFO - 1500439160313 Marionette DEBUG Register listener.js for window 15
21:39:20 INFO - 1500439160315 Marionette TRACE 368 <- [1,1,null,{"sessionId":"98c01c29-35bc-4042-a8ed-99dc4a702119","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:20 INFO - 1500439160317 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:39:20 INFO - 1500439160318 Marionette TRACE 368 <- [1,2,null,{}]
21:39:20 INFO - 1500439160320 Marionette DEBUG Closed connection 368
21:39:20 INFO - 1500439160321 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49856
21:39:20 INFO - 1500439160321 Marionette DEBUG Closed connection 369
21:39:20 INFO - 1500439160322 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49857
21:39:20 INFO - 1500439160323 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:20 INFO - 1500439160324 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:20 INFO - 1500439160326 Marionette DEBUG Register listener.js for window 15
21:39:20 INFO - 1500439160328 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ea95df4e-037c-429d-87ba-0c92ffb9f695","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:20 INFO - 1500439160330 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:39:20 INFO - 1500439160332 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160333 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160334 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160350 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160352 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160356 Marionette TRACE 370 <- [1,2,null,{}]
21:39:20 INFO - 1500439160357 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:39:20 INFO - 1500439160364 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f7530a9-4198-4939-8ef7-39004d8dea21","ELEMENT":"9f7530a9-4198-4939-8ef7-39004d8dea21"}}]
21:39:20 INFO - 1500439160367 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9f7530a9-4198-4939-8ef7-39004d8dea21"}]
21:39:20 INFO - 1500439160605 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:20 INFO - 1500439160605 Marionette TRACE 370 <- [1,4,null,{}]
21:39:20 INFO - 1500439160606 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9f7530a9-4198-4939-8ef7-39004d8dea21","name":"selected"}]
21:39:20 INFO - 1500439160610 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:39:20 INFO - 1500439160612 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
21:39:20 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500439160614 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:39:20 INFO - 1500439160615 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:39:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 343ms
21:39:20 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:39:20 INFO - 1500439160616 Marionette TRACE 370 <- [1,7,null,{}]
21:39:20 INFO - 1500439160618 Marionette DEBUG Closed connection 370
21:39:20 INFO - 1500439160619 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49858
21:39:20 INFO - 1500439160620 Marionette DEBUG Closed connection 371
21:39:20 INFO - 1500439160621 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49859
21:39:20 INFO - 1500439160622 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:20 INFO - 1500439160622 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:20 INFO - 1500439160624 Marionette DEBUG Register listener.js for window 15
21:39:20 INFO - 1500439160627 Marionette TRACE 372 <- [1,1,null,{"sessionId":"87e58dc0-8412-439b-88a9-136c9aec8e89","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:20 INFO - 1500439160629 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:39:20 INFO - 1500439160629 Marionette TRACE 372 <- [1,2,null,{}]
21:39:20 INFO - 1500439160630 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:20 INFO - 1500439160631 Marionette TRACE 372 <- [1,3,null,{}]
21:39:20 INFO - 1500439160632 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:20 INFO - 1500439160632 Marionette TRACE 372 <- [1,4,null,{}]
21:39:20 INFO - 1500439160634 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
21:39:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500439160636 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:39:20 INFO - 1500439160637 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:39:20 INFO - 1500439160638 Marionette TRACE 372 <- [1,6,null,{}]
21:39:20 INFO - 1500439160639 Marionette DEBUG Closed connection 372
21:39:20 INFO - 1500439160640 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49860
21:39:20 INFO - 1500439160641 Marionette DEBUG Closed connection 373
21:39:20 INFO - 1500439160642 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49861
21:39:20 INFO - 1500439160642 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:20 INFO - 1500439160643 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:20 INFO - 1500439160645 Marionette DEBUG Register listener.js for window 15
21:39:20 INFO - 1500439160647 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e1a92cc2-53d9-4248-8113-4bc82c96e60d","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:20 INFO - 1500439160649 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:39:20 INFO - 1500439160650 Marionette TRACE 374 <- [1,2,null,{}]
21:39:20 INFO - 1500439160652 Marionette DEBUG Closed connection 374
21:39:20 INFO - 1500439160653 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49862
21:39:20 INFO - 1500439160654 Marionette DEBUG Closed connection 375
21:39:20 INFO - 1500439160654 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49863
21:39:20 INFO - 1500439160655 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:20 INFO - 1500439160656 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:20 INFO - 1500439160658 Marionette DEBUG Register listener.js for window 15
21:39:20 INFO - 1500439160660 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e40aab21-1f65-4c25-ab3c-7593f1253e90","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:20 INFO - 1500439160662 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
21:39:20 INFO - 1500439160664 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160666 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160666 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:20 INFO - 1500439160686 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:39:20 INFO - 1500439160688 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:39:20 INFO - 1500439160691 Marionette TRACE 376 <- [1,2,null,{}]
21:39:20 INFO - 1500439160699 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:39:20 INFO - 1500439160702 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ad5926a1-9c6b-4075-9866-2aadada326f1","ELEMENT":"ad5926a1-9c6b-4075-9866-2aadada326f1"}}]
21:39:20 INFO - 1500439160703 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"ad5926a1-9c6b-4075-9866-2aadada326f1"}]
21:39:20 INFO - 1500439160939 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:20 INFO - 1500439160939 Marionette TRACE 376 <- [1,4,null,{}]
21:39:20 INFO - 1500439160941 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
21:39:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500439160944 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:39:20 INFO - 1500439160945 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:39:20 INFO - 1500439160946 Marionette TRACE 376 <- [1,6,null,{}]
21:39:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 329ms
21:39:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:39:20 INFO - 1500439160948 Marionette DEBUG Closed connection 376
21:39:20 INFO - 1500439160949 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49864
21:39:20 INFO - 1500439160950 Marionette DEBUG Closed connection 377
21:39:20 INFO - 1500439160950 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49865
21:39:20 INFO - 1500439160951 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:20 INFO - 1500439160952 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:20 INFO - 1500439160954 Marionette DEBUG Register listener.js for window 15
21:39:20 INFO - 1500439160957 Marionette TRACE 378 <- [1,1,null,{"sessionId":"0afce1fc-b1d3-4d1a-8bc7-e37592162de6","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:20 INFO - 1500439160958 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:39:20 INFO - 1500439160959 Marionette TRACE 378 <- [1,2,null,{}]
21:39:20 INFO - 1500439160960 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:20 INFO - 1500439160960 Marionette TRACE 378 <- [1,3,null,{}]
21:39:20 INFO - 1500439160961 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:20 INFO - 1500439160961 Marionette TRACE 378 <- [1,4,null,{}]
21:39:20 INFO - 1500439160963 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
21:39:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500439160965 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:39:20 INFO - 1500439160966 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:39:20 INFO - 1500439160967 Marionette TRACE 378 <- [1,6,null,{}]
21:39:20 INFO - 1500439160969 Marionette DEBUG Closed connection 378
21:39:20 INFO - 1500439160970 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49866
21:39:20 INFO - 1500439160970 Marionette DEBUG Closed connection 379
21:39:20 INFO - 1500439160971 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49867
21:39:20 INFO - 1500439160972 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:20 INFO - 1500439160973 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439160975 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439160985 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9365933d-9e8b-4ae9-ae3f-917b75b9e098","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439160987 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:39:21 INFO - 1500439160988 Marionette TRACE 380 <- [1,2,null,{}]
21:39:21 INFO - 1500439160990 Marionette DEBUG Closed connection 380
21:39:21 INFO - 1500439160990 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49868
21:39:21 INFO - 1500439160991 Marionette DEBUG Closed connection 381
21:39:21 INFO - 1500439160992 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49869
21:39:21 INFO - 1500439160993 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:21 INFO - 1500439160993 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439160995 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439160998 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ada00ad1-9162-48da-8070-333ecc85828b","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161000 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:39:21 INFO - 1500439161001 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:39:21 INFO - 1500439161003 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:39:21 INFO - 1500439161003 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:39:21 INFO - 1500439161020 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:21 INFO - 1500439161023 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:21 INFO - 1500439161026 Marionette TRACE 382 <- [1,2,null,{}]
21:39:21 INFO - 1500439161033 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:39:21 INFO - 1500439161035 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1103b38c-51eb-4c1f-84e2-60732a502887","ELEMENT":"1103b38c-51eb-4c1f-84e2-60732a502887"}}]
21:39:21 INFO - 1500439161037 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"1103b38c-51eb-4c1f-84e2-60732a502887"}]
21:39:21 INFO - 1500439161276 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:21 INFO - 1500439161276 Marionette TRACE 382 <- [1,4,null,{}]
21:39:21 INFO - 1500439161278 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
21:39:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500439161281 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:39:21 INFO - 1500439161282 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:39:21 INFO - 1500439161283 Marionette TRACE 382 <- [1,6,null,{}]
21:39:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 337ms
21:39:21 INFO - TEST-START | test_click.py TestClick.test_input_file
21:39:21 INFO - 1500439161285 Marionette DEBUG Closed connection 382
21:39:21 INFO - 1500439161286 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49870
21:39:21 INFO - 1500439161287 Marionette DEBUG Closed connection 383
21:39:21 INFO - 1500439161287 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49871
21:39:21 INFO - 1500439161288 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:21 INFO - 1500439161289 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161291 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161294 Marionette TRACE 384 <- [1,1,null,{"sessionId":"38cbd9e2-fa2a-4b07-baab-3d325f06b1a6","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161296 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:39:21 INFO - 1500439161296 Marionette TRACE 384 <- [1,2,null,{}]
21:39:21 INFO - 1500439161297 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:21 INFO - 1500439161297 Marionette TRACE 384 <- [1,3,null,{}]
21:39:21 INFO - 1500439161298 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:21 INFO - 1500439161299 Marionette TRACE 384 <- [1,4,null,{}]
21:39:21 INFO - 1500439161300 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
21:39:21 INFO - TEST-START: test_click.py TestClick.test_input_file1500439161303 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:39:21 INFO - 1500439161303 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:39:21 INFO - 1500439161304 Marionette TRACE 384 <- [1,6,null,{}]
21:39:21 INFO - 1500439161306 Marionette DEBUG Closed connection 384
21:39:21 INFO - 1500439161307 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49872
21:39:21 INFO - 1500439161307 Marionette DEBUG Closed connection 385
21:39:21 INFO - 1500439161308 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49873
21:39:21 INFO - 1500439161309 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:21 INFO - 1500439161310 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161311 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161314 Marionette TRACE 386 <- [1,1,null,{"sessionId":"ca3dcb9e-f3ee-471f-924e-f1dd6b84cabe","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161316 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:39:21 INFO - 1500439161317 Marionette TRACE 386 <- [1,2,null,{}]
21:39:21 INFO - 1500439161319 Marionette DEBUG Closed connection 386
21:39:21 INFO - 1500439161319 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49874
21:39:21 INFO - 1500439161320 Marionette DEBUG Closed connection 387
21:39:21 INFO - 1500439161321 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49875
21:39:21 INFO - 1500439161322 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:21 INFO - 1500439161322 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161324 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161327 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f104381e-0e96-4a8a-adc2-63160580d3fd","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161329 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:39:21 INFO - 1500439161330 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:21 INFO - 1500439161332 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:21 INFO - 1500439161332 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:39:21 INFO - 1500439161349 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:21 INFO - 1500439161351 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:21 INFO - 1500439161356 Marionette TRACE 388 <- [1,2,null,{}]
21:39:21 INFO - 1500439161364 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:39:21 INFO - 1500439161367 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2b24a9dc-b0b4-4c4f-865b-97318ad71e02","ELEMENT":"2b24a9dc-b0b4-4c4f-865b-97318ad71e02"}}]
21:39:21 INFO - 1500439161368 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2b24a9dc-b0b4-4c4f-865b-97318ad71e02"}]
21:39:21 INFO - 1500439161392 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]
21:39:21 INFO - 1500439161395 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
21:39:21 INFO - TEST-END: test_click.py TestClick.test_input_file1500439161397 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:39:21 INFO - 1500439161398 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:39:21 INFO - 1500439161399 Marionette TRACE 388 <- [1,6,null,{}]
21:39:21 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 115ms
21:39:21 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:39:21 INFO - 1500439161405 Marionette DEBUG Closed connection 388
21:39:21 INFO - 1500439161406 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49876
21:39:21 INFO - 1500439161407 Marionette DEBUG Closed connection 389
21:39:21 INFO - 1500439161407 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49877
21:39:21 INFO - 1500439161408 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:21 INFO - 1500439161409 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161411 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161413 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c1684d97-5d90-458e-b930-f33ac5917977","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161416 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:39:21 INFO - 1500439161416 Marionette TRACE 390 <- [1,2,null,{}]
21:39:21 INFO - 1500439161417 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:21 INFO - 1500439161417 Marionette TRACE 390 <- [1,3,null,{}]
21:39:21 INFO - 1500439161418 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:21 INFO - 1500439161419 Marionette TRACE 390 <- [1,4,null,{}]
21:39:21 INFO - 1500439161420 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
21:39:21 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500439161423 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:39:21 INFO - 1500439161423 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:39:21 INFO - 1500439161424 Marionette TRACE 390 <- [1,6,null,{}]
21:39:21 INFO - 1500439161426 Marionette DEBUG Closed connection 390
21:39:21 INFO - 1500439161427 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49878
21:39:21 INFO - 1500439161427 Marionette DEBUG Closed connection 391
21:39:21 INFO - 1500439161428 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49879
21:39:21 INFO - 1500439161429 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:21 INFO - 1500439161430 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161432 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161434 Marionette TRACE 392 <- [1,1,null,{"sessionId":"eb07201d-5be6-45f3-8ed1-d62577c6ed5a","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161436 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:39:21 INFO - 1500439161437 Marionette TRACE 392 <- [1,2,null,{}]
21:39:21 INFO - 1500439161438 Marionette DEBUG Closed connection 392
21:39:21 INFO - 1500439161439 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49880
21:39:21 INFO - 1500439161440 Marionette DEBUG Closed connection 393
21:39:21 INFO - 1500439161441 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49881
21:39:21 INFO - 1500439161445 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:21 INFO - 1500439161445 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161447 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161458 Marionette TRACE 394 <- [1,1,null,{"sessionId":"911be51e-0797-4e6d-8f0f-0dab658d36e0","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161509 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
21:39:21 INFO - 1500439161510 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:21 INFO - 1500439161512 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:21 INFO - 1500439161513 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:39:21 INFO - 1500439161523 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:39:21 INFO - 1500439161527 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:39:21 INFO - 1500439161536 Marionette TRACE 394 <- [1,2,null,{}]
21:39:21 INFO - 1500439161538 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:39:21 INFO - 1500439161540 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f52672e-6da9-4100-b289-2c3ec6b823f1","ELEMENT":"7f52672e-6da9-4100-b289-2c3ec6b823f1"}}]
21:39:21 INFO - 1500439161543 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:39:21 INFO - 1500439161545 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"977c6e98-5788-4f17-b248-35ff2b0aedd5","ELEMENT":"977c6e98-5788-4f17-b248-35ff2b0aedd5"}}]
21:39:21 INFO - 1500439161555 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7f52672e-6da9-4100-b289-2c3ec6b823f1"}]
21:39:21 INFO - 1500439161787 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:39:21 INFO - 1500439161787 Marionette TRACE 394 <- [1,5,null,{}]
21:39:21 INFO - 1500439161788 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"977c6e98-5788-4f17-b248-35ff2b0aedd5"}]
21:39:21 INFO - 1500439161802 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]
21:39:21 INFO - 1500439161804 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
21:39:21 INFO - 1500439161807 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:39:21 INFO - 1500439161809 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
21:39:21 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500439161811 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:39:21 INFO - 1500439161812 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:39:21 INFO - 1500439161813 Marionette TRACE 394 <- [1,9,null,{}]
21:39:21 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 413ms
21:39:21 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:39:21 INFO - 1500439161815 Marionette DEBUG Closed connection 394
21:39:21 INFO - 1500439161816 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49882
21:39:21 INFO - 1500439161816 Marionette DEBUG Closed connection 395
21:39:21 INFO - 1500439161817 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49883
21:39:21 INFO - 1500439161818 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:21 INFO - 1500439161819 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161821 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161823 Marionette TRACE 396 <- [1,1,null,{"sessionId":"cdda11f6-d0db-4b4b-8fa4-f04a24bb2492","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161825 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:39:21 INFO - 1500439161826 Marionette TRACE 396 <- [1,2,null,{}]
21:39:21 INFO - 1500439161826 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:39:21 INFO - 1500439161827 Marionette TRACE 396 <- [1,3,null,{}]
21:39:21 INFO - 1500439161827 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:39:21 INFO - 1500439161828 Marionette TRACE 396 <- [1,4,null,{}]
21:39:21 INFO - 1500439161830 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
21:39:21 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500439161832 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:39:21 INFO - 1500439161833 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:39:21 INFO - 1500439161834 Marionette TRACE 396 <- [1,6,null,{}]
21:39:21 INFO - 1500439161835 Marionette DEBUG Closed connection 396
21:39:21 INFO - 1500439161836 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49884
21:39:21 INFO - 1500439161837 Marionette DEBUG Closed connection 397
21:39:21 INFO - 1500439161837 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49885
21:39:21 INFO - 1500439161838 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:39:21 INFO - 1500439161839 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161841 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161843 Marionette TRACE 398 <- [1,1,null,{"sessionId":"482c1946-a950-4d37-84ec-efdbc45ea171","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161845 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:39:21 INFO - 1500439161846 Marionette TRACE 398 <- [1,2,null,{}]
21:39:21 INFO - 1500439161848 Marionette DEBUG Closed connection 398
21:39:21 INFO - 1500439161849 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49886
21:39:21 INFO - 1500439161849 Marionette DEBUG Closed connection 399
21:39:21 INFO - 1500439161850 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49887
21:39:21 INFO - 1500439161851 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:39:21 INFO - 1500439161852 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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}
21:39:21 INFO - 1500439161853 Marionette DEBUG Register listener.js for window 15
21:39:21 INFO - 1500439161856 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8b486184-764a-4347-a28d-ac3dcebbfa11","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":3472,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5bhzlv.mozrunner","moz:accessibilityChecks":false}}]
21:39:21 INFO - 1500439161858 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
21:39:21 INFO - 1500439161860 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:39:21 INFO - 1500439161861 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:39:21 INFO - 1500439161862 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:39:21 INFO - 1500439161874 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:39:21 INFO - 1500439161877 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:39:21 INFO - 1500439161887 Marionette TRACE 400 <- [1,2,null,{}]
21:39:21 INFO - 1500439161888 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:39:21 INFO - 1500439161891 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"80c5b527-34dc-4f08-9123-e1297f04bce7","ELEMENT":"80c5b527-34dc-4f08-9123-e1297f04bce7"}}]
21:39:21 INFO - 1500439161892 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"80c5b527-34dc-4f08-9123-e1297f04bce7"}]
21:39:21 INFO - 1500439161913 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:39:21 INFO - 1500439161914 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"80c5b527-34dc-4f08-9123-e1297f04bce7"}]
21:39:21 INFO - 1500439161931 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element