could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:52:49 INFO - 1500573169673 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
10:52:49 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500573169677 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:52:49 INFO - 1500573169678 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:52:49 INFO - 1500573169680 Marionette TRACE 352 <- [1,6,null,{}]
10:52:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 160ms
10:52:49 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:52:49 INFO - 1500573169683 Marionette DEBUG Closed connection 352
10:52:49 INFO - 1500573169684 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50172
10:52:49 INFO - 1500573169685 Marionette DEBUG Closed connection 353
10:52:49 INFO - 1500573169685 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50173
10:52:49 INFO - 1500573169687 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:49 INFO - 1500573169695 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:49 INFO - 1500573169698 Marionette DEBUG Register listener.js for window 15
10:52:49 INFO - 1500573169702 Marionette TRACE 354 <- [1,1,null,{"sessionId":"98626d1d-6eff-4213-9fae-4a9a4a1f1a8c","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:49 INFO - 1500573169704 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:52:49 INFO - 1500573169705 Marionette TRACE 354 <- [1,2,null,{}]
10:52:49 INFO - 1500573169706 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:52:49 INFO - 1500573169707 Marionette TRACE 354 <- [1,3,null,{}]
10:52:49 INFO - 1500573169708 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:52:49 INFO - 1500573169708 Marionette TRACE 354 <- [1,4,null,{}]
10:52:49 INFO - 1500573169710 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
10:52:49 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500573169713 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:52:49 INFO - 1500573169714 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:52:49 INFO - 1500573169716 Marionette TRACE 354 <- [1,6,null,{}]
10:52:49 INFO - 1500573169718 Marionette DEBUG Closed connection 354
10:52:49 INFO - 1500573169719 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50174
10:52:49 INFO - 1500573169720 Marionette DEBUG Closed connection 355
10:52:49 INFO - 1500573169721 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50175
10:52:49 INFO - 1500573169722 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:49 INFO - 1500573169723 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:49 INFO - 1500573169725 Marionette DEBUG Register listener.js for window 15
10:52:49 INFO - 1500573169729 Marionette TRACE 356 <- [1,1,null,{"sessionId":"4b5e7df5-cb98-4aad-9f46-251e36c7ab87","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:49 INFO - 1500573169731 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:52:49 INFO - 1500573169732 Marionette TRACE 356 <- [1,2,null,{}]
10:52:49 INFO - 1500573169735 Marionette DEBUG Closed connection 356
10:52:49 INFO - 1500573169735 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50176
10:52:49 INFO - 1500573169736 Marionette DEBUG Closed connection 357
10:52:49 INFO - 1500573169737 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50177
10:52:49 INFO - 1500573169738 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:52:49 INFO - 1500573169739 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:49 INFO - 1500573169742 Marionette DEBUG Register listener.js for window 15
10:52:49 INFO - 1500573169745 Marionette TRACE 358 <- [1,1,null,{"sessionId":"68833eb8-001e-47ed-901e-6cd097dbc72c","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:49 INFO - 1500573169747 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49737/clicks.html"}]
10:52:49 INFO - 1500573169749 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49737/hidden.html"
10:52:49 INFO - 1500573169755 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49737/hidden.html"
10:52:49 INFO - 1500573169756 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49737/hidden.html"
10:52:49 INFO - 1500573169782 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49737/clicks.html"
10:52:49 INFO - 1500573169785 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49737/clicks.html"
10:52:49 INFO - 1500573169789 Marionette TRACE 358 <- [1,2,null,{}]
10:52:49 INFO - 1500573169798 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:52:49 INFO - 1500573169801 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"378cf7a2-a88a-4b2f-9b7d-69a0ef9044a2","ELEMENT":"378cf7a2-a88a-4b2f-9b7d-69a0ef9044a2"}}]
10:52:49 INFO - 1500573169802 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"378cf7a2-a88a-4b2f-9b7d-69a0ef9044a2"}]
10:52:49 INFO - 1500573169849 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49737/clicks.html"
10:52:49 INFO - 1500573169857 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49737/clicks.html"
10:52:49 INFO - 1500573169858 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49737/clicks.html"
10:52:49 INFO - 1500573169897 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49737/test.html"
10:52:49 INFO - 1500573169902 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49737/test.html"
10:52:49 INFO - 1500573169907 Marionette TRACE 358 <- [1,4,null,{}]
10:52:49 INFO - 1500573169918 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:52:49 INFO - 1500573169921 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4e13f66e-f5e1-486d-ba7a-69c69cecf3d7","ELEMENT":"4e13f66e-f5e1-486d-ba7a-69c69cecf3d7"}}]
10:52:49 INFO - 1500573169922 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:52:49 INFO - 1500573169923 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:52:49 INFO - 1500573169925 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
10:52:49 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500573169929 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:52:49 INFO - 1500573169930 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:52:49 INFO - 1500573169932 Marionette TRACE 358 <- [1,8,null,{}]
10:52:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 251ms
10:52:49 INFO - TEST-START | test_click.py TestClick.test_container_element
10:52:49 INFO - 1500573169934 Marionette DEBUG Closed connection 358
10:52:49 INFO - 1500573169935 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50180
10:52:49 INFO - 1500573169936 Marionette DEBUG Closed connection 359
10:52:49 INFO - 1500573169937 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50181
10:52:49 INFO - 1500573169941 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:49 INFO - 1500573169942 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:49 INFO - 1500573169945 Marionette DEBUG Register listener.js for window 15
10:52:49 INFO - 1500573169949 Marionette TRACE 360 <- [1,1,null,{"sessionId":"cd505de2-4590-4ebc-a6b0-89ecc294e922","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:49 INFO - 1500573169951 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:52:49 INFO - 1500573169952 Marionette TRACE 360 <- [1,2,null,{}]
10:52:49 INFO - 1500573169953 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:52:49 INFO - 1500573169953 Marionette TRACE 360 <- [1,3,null,{}]
10:52:49 INFO - 1500573169954 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:52:49 INFO - 1500573169955 Marionette TRACE 360 <- [1,4,null,{}]
10:52:49 INFO - 1500573169958 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
10:52:49 INFO - TEST-START: test_click.py TestClick.test_container_element1500573169961 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:52:49 INFO - 1500573169962 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:52:49 INFO - 1500573169964 Marionette TRACE 360 <- [1,6,null,{}]
10:52:49 INFO - 1500573169966 Marionette DEBUG Closed connection 360
10:52:49 INFO - 1500573169967 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50182
10:52:49 INFO - 1500573169968 Marionette DEBUG Closed connection 361
10:52:49 INFO - 1500573169969 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50183
10:52:49 INFO - 1500573169970 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:49 INFO - 1500573169971 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:49 INFO - 1500573169974 Marionette DEBUG Register listener.js for window 15
10:52:49 INFO - 1500573169979 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b5e6d0b1-b8be-4d54-96a1-8eed2533057e","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:49 INFO - 1500573169981 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:52:49 INFO - 1500573169983 Marionette TRACE 362 <- [1,2,null,{}]
10:52:49 INFO - 1500573169985 Marionette DEBUG Closed connection 362
10:52:49 INFO - 1500573169986 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50184
10:52:49 INFO - 1500573169987 Marionette DEBUG Closed connection 363
10:52:49 INFO - 1500573169988 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50185
10:52:49 INFO - 1500573169990 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:52:49 INFO - 1500573169991 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:49 INFO - 1500573169993 Marionette DEBUG Register listener.js for window 15
10:52:50 INFO - 1500573169997 Marionette TRACE 364 <- [1,1,null,{"sessionId":"89d1f953-2484-4ecc-b869-8e35bd95d50d","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:50 INFO - 1500573169999 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:52:50 INFO - 1500573170001 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49737/test.html"
10:52:50 INFO - 1500573170003 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49737/test.html"
10:52:50 INFO - 1500573170004 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49737/test.html"
10:52:50 INFO - 1500573170033 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170037 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170041 Marionette TRACE 364 <- [1,2,null,{}]
10:52:50 INFO - 1500573170050 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:52:50 INFO - 1500573170053 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b03c3dad-b995-482f-b38b-cad227e2732d","ELEMENT":"b03c3dad-b995-482f-b38b-cad227e2732d"}}]
10:52:50 INFO - 1500573170054 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b03c3dad-b995-482f-b38b-cad227e2732d"}]
10:52:50 INFO - 1500573170301 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:52:50 INFO - 1500573170302 Marionette TRACE 364 <- [1,4,null,{}]
10:52:50 INFO - 1500573170303 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b03c3dad-b995-482f-b38b-cad227e2732d","name":"selected"}]
10:52:50 INFO - 1500573170308 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:52:50 INFO - 1500573170310 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
10:52:50 INFO - TEST-END: test_click.py TestClick.test_container_element1500573170313 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:52:50 INFO - 1500573170314 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:52:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 384ms
10:52:50 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:52:50 INFO - 1500573170316 Marionette TRACE 364 <- [1,7,null,{}]
10:52:50 INFO - 1500573170318 Marionette DEBUG Closed connection 364
10:52:50 INFO - 1500573170319 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50186
10:52:50 INFO - 1500573170320 Marionette DEBUG Closed connection 365
10:52:50 INFO - 1500573170320 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50187
10:52:50 INFO - 1500573170322 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:50 INFO - 1500573170322 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:50 INFO - 1500573170325 Marionette DEBUG Register listener.js for window 15
10:52:50 INFO - 1500573170343 Marionette TRACE 366 <- [1,1,null,{"sessionId":"878863d7-9248-4bc1-8d03-cd18260819bf","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:50 INFO - 1500573170347 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:52:50 INFO - 1500573170347 Marionette TRACE 366 <- [1,2,null,{}]
10:52:50 INFO - 1500573170348 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:52:50 INFO - 1500573170349 Marionette TRACE 366 <- [1,3,null,{}]
10:52:50 INFO - 1500573170350 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:52:50 INFO - 1500573170350 Marionette TRACE 366 <- [1,4,null,{}]
10:52:50 INFO - 1500573170352 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
10:52:50 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500573170355 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:52:50 INFO - 1500573170356 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:52:50 INFO - 1500573170358 Marionette TRACE 366 <- [1,6,null,{}]
10:52:50 INFO - 1500573170360 Marionette DEBUG Closed connection 366
10:52:50 INFO - 1500573170361 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50188
10:52:50 INFO - 1500573170362 Marionette DEBUG Closed connection 367
10:52:50 INFO - 1500573170363 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50189
10:52:50 INFO - 1500573170364 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:50 INFO - 1500573170364 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:50 INFO - 1500573170367 Marionette DEBUG Register listener.js for window 15
10:52:50 INFO - 1500573170370 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d86f773a-32e1-4f41-99e2-464e26aa6b01","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:50 INFO - 1500573170373 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:52:50 INFO - 1500573170374 Marionette TRACE 368 <- [1,2,null,{}]
10:52:50 INFO - 1500573170376 Marionette DEBUG Closed connection 368
10:52:50 INFO - 1500573170377 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50190
10:52:50 INFO - 1500573170378 Marionette DEBUG Closed connection 369
10:52:50 INFO - 1500573170379 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50191
10:52:50 INFO - 1500573170380 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:52:50 INFO - 1500573170381 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:50 INFO - 1500573170383 Marionette DEBUG Register listener.js for window 15
10:52:50 INFO - 1500573170387 Marionette TRACE 370 <- [1,1,null,{"sessionId":"aee83793-2d11-4f37-962a-4019e140ba59","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:50 INFO - 1500573170389 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:52:50 INFO - 1500573170391 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170393 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170394 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170415 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170418 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170422 Marionette TRACE 370 <- [1,2,null,{}]
10:52:50 INFO - 1500573170430 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:52:50 INFO - 1500573170433 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1325e9f4-5a38-4975-88c9-2466b13c9813","ELEMENT":"1325e9f4-5a38-4975-88c9-2466b13c9813"}}]
10:52:50 INFO - 1500573170434 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1325e9f4-5a38-4975-88c9-2466b13c9813"}]
10:52:50 INFO - 1500573170680 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:52:50 INFO - 1500573170681 Marionette TRACE 370 <- [1,4,null,{}]
10:52:50 INFO - 1500573170682 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1325e9f4-5a38-4975-88c9-2466b13c9813","name":"selected"}]
10:52:50 INFO - 1500573170687 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:52:50 INFO - 1500573170689 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
10:52:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500573170692 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:52:50 INFO - 1500573170693 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:52:50 INFO - 1500573170694 Marionette TRACE 370 <- [1,7,null,{}]
10:52:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 379ms
10:52:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:52:50 INFO - 1500573170697 Marionette DEBUG Closed connection 370
10:52:50 INFO - 1500573170698 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50192
10:52:50 INFO - 1500573170698 Marionette DEBUG Closed connection 371
10:52:50 INFO - 1500573170699 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50193
10:52:50 INFO - 1500573170700 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:50 INFO - 1500573170701 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:50 INFO - 1500573170704 Marionette DEBUG Register listener.js for window 15
10:52:50 INFO - 1500573170708 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2d0878e5-fe76-4fab-ab24-5e921da32f55","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:50 INFO - 1500573170710 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:52:50 INFO - 1500573170711 Marionette TRACE 372 <- [1,2,null,{}]
10:52:50 INFO - 1500573170712 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:52:50 INFO - 1500573170712 Marionette TRACE 372 <- [1,3,null,{}]
10:52:50 INFO - 1500573170713 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:52:50 INFO - 1500573170714 Marionette TRACE 372 <- [1,4,null,{}]
10:52:50 INFO - 1500573170716 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
10:52:50 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500573170719 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:52:50 INFO - 1500573170720 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:52:50 INFO - 1500573170721 Marionette TRACE 372 <- [1,6,null,{}]
10:52:50 INFO - 1500573170723 Marionette DEBUG Closed connection 372
10:52:50 INFO - 1500573170724 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50194
10:52:50 INFO - 1500573170725 Marionette DEBUG Closed connection 373
10:52:50 INFO - 1500573170726 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50195
10:52:50 INFO - 1500573170727 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:50 INFO - 1500573170728 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:50 INFO - 1500573170730 Marionette DEBUG Register listener.js for window 15
10:52:50 INFO - 1500573170734 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f2ea5f2a-afb2-400b-9230-32aadd999974","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:50 INFO - 1500573170736 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:52:50 INFO - 1500573170738 Marionette TRACE 374 <- [1,2,null,{}]
10:52:50 INFO - 1500573170740 Marionette DEBUG Closed connection 374
10:52:50 INFO - 1500573170741 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50196
10:52:50 INFO - 1500573170742 Marionette DEBUG Closed connection 375
10:52:50 INFO - 1500573170743 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50197
10:52:50 INFO - 1500573170744 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:52:50 INFO - 1500573170744 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:50 INFO - 1500573170747 Marionette DEBUG Register listener.js for window 15
10:52:50 INFO - 1500573170750 Marionette TRACE 376 <- [1,1,null,{"sessionId":"593d26bf-e88a-40dc-a853-53d04f903294","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:50 INFO - 1500573170753 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
10:52:50 INFO - 1500573170754 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170757 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170757 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:50 INFO - 1500573170771 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:52:50 INFO - 1500573170776 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:52:50 INFO - 1500573170789 Marionette TRACE 376 <- [1,2,null,{}]
10:52:50 INFO - 1500573170792 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:52:50 INFO - 1500573170795 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"453afcc6-ac4f-4940-bc41-4332ea8f5513","ELEMENT":"453afcc6-ac4f-4940-bc41-4332ea8f5513"}}]
10:52:50 INFO - 1500573170798 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"453afcc6-ac4f-4940-bc41-4332ea8f5513"}]
10:52:51 INFO - 1500573171040 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:52:51 INFO - 1500573171041 Marionette TRACE 376 <- [1,4,null,{}]
10:52:51 INFO - 1500573171043 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
10:52:51 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500573171047 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:52:51 INFO - 1500573171048 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:52:51 INFO - 1500573171049 Marionette TRACE 376 <- [1,6,null,{}]
10:52:51 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 355ms
10:52:51 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:52:51 INFO - 1500573171052 Marionette DEBUG Closed connection 376
10:52:51 INFO - 1500573171053 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50198
10:52:51 INFO - 1500573171053 Marionette DEBUG Closed connection 377
10:52:51 INFO - 1500573171054 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50199
10:52:51 INFO - 1500573171055 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:51 INFO - 1500573171056 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171059 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171070 Marionette TRACE 378 <- [1,1,null,{"sessionId":"85b6fd7c-a3a9-4ace-adbc-8141299265df","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171072 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:52:51 INFO - 1500573171073 Marionette TRACE 378 <- [1,2,null,{}]
10:52:51 INFO - 1500573171074 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:52:51 INFO - 1500573171074 Marionette TRACE 378 <- [1,3,null,{}]
10:52:51 INFO - 1500573171075 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:52:51 INFO - 1500573171076 Marionette TRACE 378 <- [1,4,null,{}]
10:52:51 INFO - 1500573171078 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
10:52:51 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500573171081 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:52:51 INFO - 1500573171082 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:52:51 INFO - 1500573171083 Marionette TRACE 378 <- [1,6,null,{}]
10:52:51 INFO - 1500573171086 Marionette DEBUG Closed connection 378
10:52:51 INFO - 1500573171087 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50200
10:52:51 INFO - 1500573171088 Marionette DEBUG Closed connection 379
10:52:51 INFO - 1500573171088 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50201
10:52:51 INFO - 1500573171089 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:51 INFO - 1500573171090 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171093 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171096 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d6df8f73-8f54-4262-b387-d40a3e7189df","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171098 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:52:51 INFO - 1500573171100 Marionette TRACE 380 <- [1,2,null,{}]
10:52:51 INFO - 1500573171102 Marionette DEBUG Closed connection 380
10:52:51 INFO - 1500573171103 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50202
10:52:51 INFO - 1500573171104 Marionette DEBUG Closed connection 381
10:52:51 INFO - 1500573171105 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50203
10:52:51 INFO - 1500573171106 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:52:51 INFO - 1500573171106 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171109 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171114 Marionette TRACE 382 <- [1,1,null,{"sessionId":"6989c97f-f418-4e82-b270-67b02b951278","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171117 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:52:51 INFO - 1500573171118 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:52:51 INFO - 1500573171121 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:52:51 INFO - 1500573171121 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:52:51 INFO - 1500573171142 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:51 INFO - 1500573171146 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:51 INFO - 1500573171150 Marionette TRACE 382 <- [1,2,null,{}]
10:52:51 INFO - 1500573171152 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:52:51 INFO - 1500573171155 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d7a6b557-777d-46a6-a30e-d6bc123d888d","ELEMENT":"d7a6b557-777d-46a6-a30e-d6bc123d888d"}}]
10:52:51 INFO - 1500573171164 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d7a6b557-777d-46a6-a30e-d6bc123d888d"}]
10:52:51 INFO - 1500573171410 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:52:51 INFO - 1500573171410 Marionette TRACE 382 <- [1,4,null,{}]
10:52:51 INFO - 1500573171413 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
10:52:51 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500573171416 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:52:51 INFO - 1500573171417 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:52:51 INFO - 1500573171418 Marionette TRACE 382 <- [1,6,null,{}]
10:52:51 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 369ms
10:52:51 INFO - TEST-START | test_click.py TestClick.test_input_file
10:52:51 INFO - 1500573171421 Marionette DEBUG Closed connection 382
10:52:51 INFO - 1500573171422 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50204
10:52:51 INFO - 1500573171423 Marionette DEBUG Closed connection 383
10:52:51 INFO - 1500573171423 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50205
10:52:51 INFO - 1500573171424 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:51 INFO - 1500573171425 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171428 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171432 Marionette TRACE 384 <- [1,1,null,{"sessionId":"9af09a82-db98-40be-bf2a-63a2e0ca9583","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171434 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:52:51 INFO - 1500573171435 Marionette TRACE 384 <- [1,2,null,{}]
10:52:51 INFO - 1500573171436 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:52:51 INFO - 1500573171436 Marionette TRACE 384 <- [1,3,null,{}]
10:52:51 INFO - 1500573171437 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:52:51 INFO - 1500573171438 Marionette TRACE 384 <- [1,4,null,{}]
10:52:51 INFO - 1500573171440 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
10:52:51 INFO - TEST-START: test_click.py TestClick.test_input_file1500573171443 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:52:51 INFO - 1500573171444 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:52:51 INFO - 1500573171445 Marionette TRACE 384 <- [1,6,null,{}]
10:52:51 INFO - 1500573171447 Marionette DEBUG Closed connection 384
10:52:51 INFO - 1500573171448 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50206
10:52:51 INFO - 1500573171449 Marionette DEBUG Closed connection 385
10:52:51 INFO - 1500573171450 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50207
10:52:51 INFO - 1500573171451 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:51 INFO - 1500573171452 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171454 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171458 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6f5aa0b2-2e3f-4834-bc2f-868737845d30","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171460 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:52:51 INFO - 1500573171461 Marionette TRACE 386 <- [1,2,null,{}]
10:52:51 INFO - 1500573171464 Marionette DEBUG Closed connection 386
10:52:51 INFO - 1500573171464 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50208
10:52:51 INFO - 1500573171465 Marionette DEBUG Closed connection 387
10:52:51 INFO - 1500573171466 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50209
10:52:51 INFO - 1500573171467 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:52:51 INFO - 1500573171468 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171471 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171474 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4f10a2f4-fcd3-4cdd-95b5-758c7ea14be5","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171477 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:52:51 INFO - 1500573171478 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:51 INFO - 1500573171481 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:51 INFO - 1500573171482 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:52:51 INFO - 1500573171505 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:52:51 INFO - 1500573171511 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:52:51 INFO - 1500573171515 Marionette TRACE 388 <- [1,2,null,{}]
10:52:51 INFO - 1500573171516 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:52:51 INFO - 1500573171519 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"15c71be0-87f8-4204-96e4-0d028a64f40a","ELEMENT":"15c71be0-87f8-4204-96e4-0d028a64f40a"}}]
10:52:51 INFO - 1500573171520 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"15c71be0-87f8-4204-96e4-0d028a64f40a"}]
10:52:51 INFO - 1500573171557 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:52:51 INFO - 1500573171560 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
10:52:51 INFO - TEST-END: test_click.py TestClick.test_input_file1500573171563 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:52:51 INFO - 1500573171565 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:52:51 INFO - 1500573171566 Marionette TRACE 388 <- [1,6,null,{}]
10:52:51 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 147ms
10:52:51 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:52:51 INFO - 1500573171569 Marionette DEBUG Closed connection 388
10:52:51 INFO - 1500573171569 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50210
10:52:51 INFO - 1500573171570 Marionette DEBUG Closed connection 389
10:52:51 INFO - 1500573171571 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50211
10:52:51 INFO - 1500573171572 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:51 INFO - 1500573171573 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171576 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171579 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1f01a9cd-39b6-4d2b-9e9e-ffab8dd304a6","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171582 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:52:51 INFO - 1500573171582 Marionette TRACE 390 <- [1,2,null,{}]
10:52:51 INFO - 1500573171583 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:52:51 INFO - 1500573171584 Marionette TRACE 390 <- [1,3,null,{}]
10:52:51 INFO - 1500573171585 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:52:51 INFO - 1500573171585 Marionette TRACE 390 <- [1,4,null,{}]
10:52:51 INFO - 1500573171587 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
10:52:51 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500573171590 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:52:51 INFO - 1500573171591 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:52:51 INFO - 1500573171593 Marionette TRACE 390 <- [1,6,null,{}]
10:52:51 INFO - 1500573171595 Marionette DEBUG Closed connection 390
10:52:51 INFO - 1500573171596 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50212
10:52:51 INFO - 1500573171597 Marionette DEBUG Closed connection 391
10:52:51 INFO - 1500573171597 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50213
10:52:51 INFO - 1500573171598 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:51 INFO - 1500573171599 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171602 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171613 Marionette TRACE 392 <- [1,1,null,{"sessionId":"82a0c991-3f55-4763-baf3-b923a46414b5","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171617 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:52:51 INFO - 1500573171619 Marionette TRACE 392 <- [1,2,null,{}]
10:52:51 INFO - 1500573171621 Marionette DEBUG Closed connection 392
10:52:51 INFO - 1500573171622 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50214
10:52:51 INFO - 1500573171624 Marionette DEBUG Closed connection 393
10:52:51 INFO - 1500573171625 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50215
10:52:51 INFO - 1500573171626 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:52:51 INFO - 1500573171627 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171630 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171633 Marionette TRACE 394 <- [1,1,null,{"sessionId":"6b1a8348-d256-4fd8-abaf-9aab5e92054d","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171636 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
10:52:51 INFO - 1500573171638 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:52:51 INFO - 1500573171640 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:52:51 INFO - 1500573171641 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:52:51 INFO - 1500573171654 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:52:51 INFO - 1500573171659 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:52:51 INFO - 1500573171671 Marionette TRACE 394 <- [1,2,null,{}]
10:52:51 INFO - 1500573171676 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:52:51 INFO - 1500573171678 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"90612aaf-717e-4e28-84e8-24a1ef9098ac","ELEMENT":"90612aaf-717e-4e28-84e8-24a1ef9098ac"}}]
10:52:51 INFO - 1500573171680 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:52:51 INFO - 1500573171682 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d2555589-f61b-4b54-bd30-e97835d1626e","ELEMENT":"d2555589-f61b-4b54-bd30-e97835d1626e"}}]
10:52:51 INFO - 1500573171685 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"90612aaf-717e-4e28-84e8-24a1ef9098ac"}]
10:52:51 INFO - 1500573171925 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:52:51 INFO - 1500573171926 Marionette TRACE 394 <- [1,5,null,{}]
10:52:51 INFO - 1500573171928 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d2555589-f61b-4b54-bd30-e97835d1626e"}]
10:52:51 INFO - 1500573171946 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:52:51 INFO - 1500573171948 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
10:52:51 INFO - 1500573171951 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:52:51 INFO - 1500573171952 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
10:52:51 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500573171956 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:52:51 INFO - 1500573171957 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:52:51 INFO - 1500573171958 Marionette TRACE 394 <- [1,9,null,{}]
10:52:51 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 391ms
10:52:51 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:52:51 INFO - 1500573171961 Marionette DEBUG Closed connection 394
10:52:51 INFO - 1500573171961 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50216
10:52:51 INFO - 1500573171962 Marionette DEBUG Closed connection 395
10:52:51 INFO - 1500573171963 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50217
10:52:51 INFO - 1500573171964 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:51 INFO - 1500573171965 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:51 INFO - 1500573171967 Marionette DEBUG Register listener.js for window 15
10:52:51 INFO - 1500573171971 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9abe5a7e-c282-4055-bce4-ea8b7bab9671","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:51 INFO - 1500573171973 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:52:51 INFO - 1500573171974 Marionette TRACE 396 <- [1,2,null,{}]
10:52:51 INFO - 1500573171975 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:52:51 INFO - 1500573171975 Marionette TRACE 396 <- [1,3,null,{}]
10:52:51 INFO - 1500573171976 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:52:51 INFO - 1500573171977 Marionette TRACE 396 <- [1,4,null,{}]
10:52:51 INFO - 1500573171979 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
10:52:51 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500573171982 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:52:51 INFO - 1500573171983 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:52:51 INFO - 1500573171984 Marionette TRACE 396 <- [1,6,null,{}]
10:52:51 INFO - 1500573171986 Marionette DEBUG Closed connection 396
10:52:51 INFO - 1500573171987 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50218
10:52:52 INFO - 1500573172002 Marionette DEBUG Closed connection 397
10:52:52 INFO - 1500573172003 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50219
10:52:52 INFO - 1500573172005 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:52:52 INFO - 1500573172005 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:52 INFO - 1500573172008 Marionette DEBUG Register listener.js for window 15
10:52:52 INFO - 1500573172012 Marionette TRACE 398 <- [1,1,null,{"sessionId":"dcc30fcf-e22a-4a1d-883d-b82d38a93358","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:52 INFO - 1500573172015 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:52:52 INFO - 1500573172017 Marionette TRACE 398 <- [1,2,null,{}]
10:52:52 INFO - 1500573172020 Marionette DEBUG Closed connection 398
10:52:52 INFO - 1500573172021 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50220
10:52:52 INFO - 1500573172022 Marionette DEBUG Closed connection 399
10:52:52 INFO - 1500573172023 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50221
10:52:52 INFO - 1500573172024 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:52:52 INFO - 1500573172025 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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}
10:52:52 INFO - 1500573172028 Marionette DEBUG Register listener.js for window 15
10:52:52 INFO - 1500573172031 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8d646e40-1019-4ccb-a70e-f79552f20f1c","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":2028,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpoby341.mozrunner","moz:accessibilityChecks":false}}]
10:52:52 INFO - 1500573172033 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
10:52:52 INFO - 1500573172035 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:52:52 INFO - 1500573172037 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:52:52 INFO - 1500573172038 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:52:52 INFO - 1500573172063 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
10:52:52 INFO - 1500573172064 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
10:52:52 INFO - 1500573172068 Marionette TRACE 400 <- [1,2,null,{}]
10:52:52 INFO - 1500573172071 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:52:52 INFO - 1500573172081 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"749d7f7d-d65a-4462-a664-cb47d938e7a0","ELEMENT":"749d7f7d-d65a-4462-a664-cb47d938e7a0"}}]
10:52:52 INFO - 1500573172083 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"749d7f7d-d65a-4462-a664-cb47d938e7a0"}]
10:52:52 INFO - 1500573172108 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:52:52 INFO - 1500573172109 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"749d7f7d-d65a-4462-a664-cb47d938e7a0"}]
10:52:52 INFO - 1500573172132 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element