could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
23:01:45 INFO - 1500962505679 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}]
23:01:45 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500962505682 Marionette TRACE 352 <- [1,5,null,{"value":null}]
23:01:45 INFO - 1500962505683 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
23:01:45 INFO - 1500962505684 Marionette TRACE 352 <- [1,6,null,{}]
23:01:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 179ms
23:01:45 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
23:01:45 INFO - 1500962505686 Marionette DEBUG Closed connection 352
23:01:45 INFO - 1500962505687 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49766
23:01:45 INFO - 1500962505688 Marionette DEBUG Closed connection 353
23:01:45 INFO - 1500962505688 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49767
23:01:45 INFO - 1500962505689 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:45 INFO - 1500962505690 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:45 INFO - 1500962505693 Marionette DEBUG Register listener.js for window 12
23:01:45 INFO - 1500962505696 Marionette TRACE 354 <- [1,1,null,{"sessionId":"fa45fd38-bcdc-4e8e-a92f-aa101f328c22","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:45 INFO - 1500962505698 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
23:01:45 INFO - 1500962505699 Marionette TRACE 354 <- [1,2,null,{}]
23:01:45 INFO - 1500962505700 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:45 INFO - 1500962505701 Marionette TRACE 354 <- [1,3,null,{}]
23:01:45 INFO - 1500962505702 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:45 INFO - 1500962505702 Marionette TRACE 354 <- [1,4,null,{}]
23:01:45 INFO - 1500962505704 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}]
23:01:45 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500962505706 Marionette TRACE 354 <- [1,5,null,{"value":null}]
23:01:45 INFO - 1500962505707 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
23:01:45 INFO - 1500962505709 Marionette TRACE 354 <- [1,6,null,{}]
23:01:45 INFO - 1500962505711 Marionette DEBUG Closed connection 354
23:01:45 INFO - 1500962505712 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49768
23:01:45 INFO - 1500962505713 Marionette DEBUG Closed connection 355
23:01:45 INFO - 1500962505713 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49769
23:01:45 INFO - 1500962505714 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:45 INFO - 1500962505715 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:45 INFO - 1500962505717 Marionette DEBUG Register listener.js for window 12
23:01:45 INFO - 1500962505720 Marionette TRACE 356 <- [1,1,null,{"sessionId":"6aa17f34-7681-4ef2-b2e7-ca404b3ce104","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:45 INFO - 1500962505722 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
23:01:45 INFO - 1500962505724 Marionette TRACE 356 <- [1,2,null,{}]
23:01:45 INFO - 1500962505726 Marionette DEBUG Closed connection 356
23:01:45 INFO - 1500962505727 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49770
23:01:45 INFO - 1500962505728 Marionette DEBUG Closed connection 357
23:01:45 INFO - 1500962505728 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49771
23:01:45 INFO - 1500962505729 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:45 INFO - 1500962505730 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:45 INFO - 1500962505733 Marionette DEBUG Register listener.js for window 12
23:01:45 INFO - 1500962505736 Marionette TRACE 358 <- [1,1,null,{"sessionId":"5efc0f58-3eef-44fb-b9b8-d6e5e44e41f3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:45 INFO - 1500962505738 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49335/clicks.html"}]
23:01:45 INFO - 1500962505740 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/hidden.html"
23:01:45 INFO - 1500962505745 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/hidden.html"
23:01:45 INFO - 1500962505745 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/hidden.html"
23:01:45 INFO - 1500962505763 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/clicks.html"
23:01:45 INFO - 1500962505768 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/clicks.html"
23:01:45 INFO - 1500962505780 Marionette TRACE 358 <- [1,2,null,{}]
23:01:45 INFO - 1500962505781 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
23:01:45 INFO - 1500962505783 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a6012a8a-99e5-4f01-838b-68c53a5790c0","ELEMENT":"a6012a8a-99e5-4f01-838b-68c53a5790c0"}}]
23:01:45 INFO - 1500962505784 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a6012a8a-99e5-4f01-838b-68c53a5790c0"}]
23:01:45 INFO - 1500962505834 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/clicks.html"
23:01:45 INFO - 1500962505843 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/clicks.html"
23:01:45 INFO - 1500962505844 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/clicks.html"
23:01:45 INFO - 1500962505865 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/test.html"
23:01:45 INFO - 1500962505867 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/test.html"
23:01:45 INFO - 1500962505870 Marionette TRACE 358 <- [1,4,null,{}]
23:01:45 INFO - 1500962505877 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
23:01:45 INFO - 1500962505880 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21a99c11-74b0-4b7b-ba9d-3131d2450209","ELEMENT":"21a99c11-74b0-4b7b-ba9d-3131d2450209"}}]
23:01:45 INFO - 1500962505881 Marionette TRACE 358 -> [0,6,"getTitle",{}]
23:01:45 INFO - 1500962505881 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
23:01:45 INFO - 1500962505883 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}]
23:01:45 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500962505886 Marionette TRACE 358 <- [1,7,null,{"value":null}]
23:01:45 INFO - 1500962505888 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
23:01:45 INFO - 1500962505889 Marionette TRACE 358 <- [1,8,null,{}]
23:01:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 205ms
23:01:45 INFO - TEST-START | test_click.py TestClick.test_container_element
23:01:45 INFO - 1500962505892 Marionette DEBUG Closed connection 358
23:01:45 INFO - 1500962505892 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49774
23:01:45 INFO - 1500962505893 Marionette DEBUG Closed connection 359
23:01:45 INFO - 1500962505894 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49775
23:01:45 INFO - 1500962505895 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:45 INFO - 1500962505896 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:45 INFO - 1500962505898 Marionette DEBUG Register listener.js for window 12
23:01:45 INFO - 1500962505902 Marionette TRACE 360 <- [1,1,null,{"sessionId":"1b82f8c8-a254-4ea0-8a8f-b0ddbb720f42","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:45 INFO - 1500962505904 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
23:01:45 INFO - 1500962505905 Marionette TRACE 360 <- [1,2,null,{}]
23:01:45 INFO - 1500962505906 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:45 INFO - 1500962505906 Marionette TRACE 360 <- [1,3,null,{}]
23:01:45 INFO - 1500962505907 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:45 INFO - 1500962505907 Marionette TRACE 360 <- [1,4,null,{}]
23:01:45 INFO - 1500962505909 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}]
23:01:45 INFO - TEST-START: test_click.py TestClick.test_container_element1500962505912 Marionette TRACE 360 <- [1,5,null,{"value":null}]
23:01:45 INFO - 1500962505913 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
23:01:45 INFO - 1500962505914 Marionette TRACE 360 <- [1,6,null,{}]
23:01:45 INFO - 1500962505917 Marionette DEBUG Closed connection 360
23:01:45 INFO - 1500962505917 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49776
23:01:45 INFO - 1500962505918 Marionette DEBUG Closed connection 361
23:01:45 INFO - 1500962505919 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49777
23:01:45 INFO - 1500962505920 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:45 INFO - 1500962505920 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:45 INFO - 1500962505923 Marionette DEBUG Register listener.js for window 12
23:01:45 INFO - 1500962505926 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f926687d-a252-42da-a300-008e87b5ca80","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:45 INFO - 1500962505929 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
23:01:45 INFO - 1500962505930 Marionette TRACE 362 <- [1,2,null,{}]
23:01:45 INFO - 1500962505932 Marionette DEBUG Closed connection 362
23:01:45 INFO - 1500962505933 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49778
23:01:45 INFO - 1500962505934 Marionette DEBUG Closed connection 363
23:01:45 INFO - 1500962505934 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49779
23:01:45 INFO - 1500962505935 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:45 INFO - 1500962505936 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:45 INFO - 1500962505938 Marionette DEBUG Register listener.js for window 12
23:01:45 INFO - 1500962505941 Marionette TRACE 364 <- [1,1,null,{"sessionId":"d1dad9cd-8ae4-42f8-a5f6-86a618b25121","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:45 INFO - 1500962505944 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"}]
23:01:45 INFO - 1500962505945 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/test.html"
23:01:45 INFO - 1500962505947 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/test.html"
23:01:45 INFO - 1500962505947 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/test.html"
23:01:45 INFO - 1500962505972 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"
23:01:45 INFO - 1500962505975 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"
23:01:45 INFO - 1500962505978 Marionette TRACE 364 <- [1,2,null,{}]
23:01:45 INFO - 1500962505980 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
23:01:45 INFO - 1500962505983 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8cfdc131-c2ca-477b-a967-7d6f0ba255f2","ELEMENT":"8cfdc131-c2ca-477b-a967-7d6f0ba255f2"}}]
23:01:45 INFO - 1500962505984 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8cfdc131-c2ca-477b-a967-7d6f0ba255f2"}]
23:01:46 INFO - 1500962506234 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:46 INFO - 1500962506235 Marionette TRACE 364 <- [1,4,null,{}]
23:01:46 INFO - 1500962506236 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8cfdc131-c2ca-477b-a967-7d6f0ba255f2","name":"selected"}]
23:01:46 INFO - 1500962506241 Marionette TRACE 364 <- [1,5,null,{"value":true}]
23:01:46 INFO - 1500962506243 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}]
23:01:46 INFO - TEST-END: test_click.py TestClick.test_container_element1500962506245 Marionette TRACE 364 <- [1,6,null,{"value":null}]
23:01:46 INFO - 1500962506247 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
23:01:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 359ms
23:01:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
23:01:46 INFO - 1500962506248 Marionette TRACE 364 <- [1,7,null,{}]
23:01:46 INFO - 1500962506250 Marionette DEBUG Closed connection 364
23:01:46 INFO - 1500962506252 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49780
23:01:46 INFO - 1500962506253 Marionette DEBUG Closed connection 365
23:01:46 INFO - 1500962506253 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49781
23:01:46 INFO - 1500962506254 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:46 INFO - 1500962506255 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:46 INFO - 1500962506258 Marionette DEBUG Register listener.js for window 12
23:01:46 INFO - 1500962506261 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ebd5918f-9a9e-4e40-bc26-2c4990953e10","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:46 INFO - 1500962506264 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
23:01:46 INFO - 1500962506264 Marionette TRACE 366 <- [1,2,null,{}]
23:01:46 INFO - 1500962506265 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:46 INFO - 1500962506265 Marionette TRACE 366 <- [1,3,null,{}]
23:01:46 INFO - 1500962506266 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:46 INFO - 1500962506268 Marionette TRACE 366 <- [1,4,null,{}]
23:01:46 INFO - 1500962506270 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}]
23:01:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500962506274 Marionette TRACE 366 <- [1,5,null,{"value":null}]
23:01:46 INFO - 1500962506276 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
23:01:46 INFO - 1500962506277 Marionette TRACE 366 <- [1,6,null,{}]
23:01:46 INFO - 1500962506280 Marionette DEBUG Closed connection 366
23:01:46 INFO - 1500962506281 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49782
23:01:46 INFO - 1500962506281 Marionette DEBUG Closed connection 367
23:01:46 INFO - 1500962506282 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49783
23:01:46 INFO - 1500962506283 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:46 INFO - 1500962506291 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:46 INFO - 1500962506294 Marionette DEBUG Register listener.js for window 12
23:01:46 INFO - 1500962506298 Marionette TRACE 368 <- [1,1,null,{"sessionId":"72965ccf-28aa-4e20-8a96-55528d1efa28","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:46 INFO - 1500962506300 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
23:01:46 INFO - 1500962506301 Marionette TRACE 368 <- [1,2,null,{}]
23:01:46 INFO - 1500962506305 Marionette DEBUG Closed connection 368
23:01:46 INFO - 1500962506306 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49784
23:01:46 INFO - 1500962506307 Marionette DEBUG Closed connection 369
23:01:46 INFO - 1500962506307 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49785
23:01:46 INFO - 1500962506308 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:46 INFO - 1500962506309 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:46 INFO - 1500962506312 Marionette DEBUG Register listener.js for window 12
23:01:46 INFO - 1500962506315 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ab8f21f9-ce81-4a49-85a9-d6d74d94e5e0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:46 INFO - 1500962506317 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"}]
23:01:46 INFO - 1500962506319 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"
23:01:46 INFO - 1500962506321 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"
23:01:46 INFO - 1500962506321 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"
23:01:46 INFO - 1500962506333 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"
23:01:46 INFO - 1500962506343 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"
23:01:46 INFO - 1500962506352 Marionette TRACE 370 <- [1,2,null,{}]
23:01:46 INFO - 1500962506361 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
23:01:46 INFO - 1500962506363 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"932f5098-7c3c-4114-b802-56101fc60985","ELEMENT":"932f5098-7c3c-4114-b802-56101fc60985"}}]
23:01:46 INFO - 1500962506364 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"932f5098-7c3c-4114-b802-56101fc60985"}]
23:01:46 INFO - 1500962506609 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:46 INFO - 1500962506609 Marionette TRACE 370 <- [1,4,null,{}]
23:01:46 INFO - 1500962506610 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"932f5098-7c3c-4114-b802-56101fc60985","name":"selected"}]
23:01:46 INFO - 1500962506615 Marionette TRACE 370 <- [1,5,null,{"value":true}]
23:01:46 INFO - 1500962506616 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}]
23:01:46 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500962506619 Marionette TRACE 370 <- [1,6,null,{"value":null}]
23:01:46 INFO - 1500962506620 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
23:01:46 INFO - 1500962506621 Marionette TRACE 370 <- [1,7,null,{}]
23:01:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 373ms
23:01:46 INFO - TEST-START | test_click.py TestClick.test_css_transforms
23:01:46 INFO - 1500962506623 Marionette DEBUG Closed connection 370
23:01:46 INFO - 1500962506624 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49786
23:01:46 INFO - 1500962506625 Marionette DEBUG Closed connection 371
23:01:46 INFO - 1500962506626 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49787
23:01:46 INFO - 1500962506627 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:46 INFO - 1500962506627 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:46 INFO - 1500962506630 Marionette DEBUG Register listener.js for window 12
23:01:46 INFO - 1500962506633 Marionette TRACE 372 <- [1,1,null,{"sessionId":"02ae88f8-9821-4d61-baf0-03b73e7e880b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:46 INFO - 1500962506635 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
23:01:46 INFO - 1500962506636 Marionette TRACE 372 <- [1,2,null,{}]
23:01:46 INFO - 1500962506637 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:46 INFO - 1500962506637 Marionette TRACE 372 <- [1,3,null,{}]
23:01:46 INFO - 1500962506638 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:46 INFO - 1500962506638 Marionette TRACE 372 <- [1,4,null,{}]
23:01:46 INFO - 1500962506640 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}]
23:01:46 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500962506642 Marionette TRACE 372 <- [1,5,null,{"value":null}]
23:01:46 INFO - 1500962506643 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
23:01:46 INFO - 1500962506644 Marionette TRACE 372 <- [1,6,null,{}]
23:01:46 INFO - 1500962506646 Marionette DEBUG Closed connection 372
23:01:46 INFO - 1500962506647 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49788
23:01:46 INFO - 1500962506648 Marionette DEBUG Closed connection 373
23:01:46 INFO - 1500962506648 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49789
23:01:46 INFO - 1500962506650 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:46 INFO - 1500962506650 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:46 INFO - 1500962506653 Marionette DEBUG Register listener.js for window 12
23:01:46 INFO - 1500962506656 Marionette TRACE 374 <- [1,1,null,{"sessionId":"3d0a38ef-0032-4f33-8213-9ceb84eec961","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:46 INFO - 1500962506658 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
23:01:46 INFO - 1500962506659 Marionette TRACE 374 <- [1,2,null,{}]
23:01:46 INFO - 1500962506662 Marionette DEBUG Closed connection 374
23:01:46 INFO - 1500962506662 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49790
23:01:46 INFO - 1500962506663 Marionette DEBUG Closed connection 375
23:01:46 INFO - 1500962506664 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49791
23:01:46 INFO - 1500962506665 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:46 INFO - 1500962506666 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:46 INFO - 1500962506668 Marionette DEBUG Register listener.js for window 12
23:01:46 INFO - 1500962506671 Marionette TRACE 376 <- [1,1,null,{"sessionId":"507f2ebc-200c-4e16-9d99-8259803247ff","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:46 INFO - 1500962506673 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"}]
23:01:46 INFO - 1500962506675 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"
23:01:46 INFO - 1500962506677 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"
23:01:46 INFO - 1500962506677 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"
23:01:46 INFO - 1500962506699 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"
23:01:46 INFO - 1500962506701 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"
23:01:46 INFO - 1500962506710 Marionette TRACE 376 <- [1,2,null,{}]
23:01:46 INFO - 1500962506711 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
23:01:46 INFO - 1500962506714 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f8cda578-e7db-42b7-b2cb-07b7f837e168","ELEMENT":"f8cda578-e7db-42b7-b2cb-07b7f837e168"}}]
23:01:46 INFO - 1500962506715 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f8cda578-e7db-42b7-b2cb-07b7f837e168"}]
23:01:46 INFO - 1500962506963 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:46 INFO - 1500962506963 Marionette TRACE 376 <- [1,4,null,{}]
23:01:46 INFO - 1500962506965 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}]
23:01:46 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500962506968 Marionette TRACE 376 <- [1,5,null,{"value":null}]
23:01:46 INFO - 1500962506969 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
23:01:46 INFO - 1500962506970 Marionette TRACE 376 <- [1,6,null,{}]
23:01:46 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 349ms
23:01:46 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
23:01:46 INFO - 1500962506972 Marionette DEBUG Closed connection 376
23:01:46 INFO - 1500962506973 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49792
23:01:46 INFO - 1500962506974 Marionette DEBUG Closed connection 377
23:01:46 INFO - 1500962506975 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49793
23:01:46 INFO - 1500962506976 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:46 INFO - 1500962506976 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:46 INFO - 1500962506979 Marionette DEBUG Register listener.js for window 12
23:01:46 INFO - 1500962506982 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c860524d-fb94-4fd3-96bd-d8cd026abf90","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:46 INFO - 1500962506984 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
23:01:46 INFO - 1500962506985 Marionette TRACE 378 <- [1,2,null,{}]
23:01:46 INFO - 1500962506986 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:46 INFO - 1500962506986 Marionette TRACE 378 <- [1,3,null,{}]
23:01:46 INFO - 1500962506987 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:46 INFO - 1500962506988 Marionette TRACE 378 <- [1,4,null,{}]
23:01:46 INFO - 1500962506989 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}]
23:01:46 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500962506992 Marionette TRACE 378 <- [1,5,null,{"value":null}]
23:01:46 INFO - 1500962506993 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
23:01:46 INFO - 1500962506994 Marionette TRACE 378 <- [1,6,null,{}]
23:01:46 INFO - 1500962506996 Marionette DEBUG Closed connection 378
23:01:46 INFO - 1500962506997 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49794
23:01:47 INFO - 1500962506997 Marionette DEBUG Closed connection 379
23:01:47 INFO - 1500962506998 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49795
23:01:47 INFO - 1500962506999 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:47 INFO - 1500962506999 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:47 INFO - 1500962507002 Marionette DEBUG Register listener.js for window 12
23:01:47 INFO - 1500962507005 Marionette TRACE 380 <- [1,1,null,{"sessionId":"11c5b3db-87d2-4e4f-9fbb-dfdc91dc880c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:47 INFO - 1500962507007 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
23:01:47 INFO - 1500962507008 Marionette TRACE 380 <- [1,2,null,{}]
23:01:47 INFO - 1500962507010 Marionette DEBUG Closed connection 380
23:01:47 INFO - 1500962507011 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49796
23:01:47 INFO - 1500962507012 Marionette DEBUG Closed connection 381
23:01:47 INFO - 1500962507012 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49797
23:01:47 INFO - 1500962507014 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:47 INFO - 1500962507014 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:47 INFO - 1500962507017 Marionette DEBUG Register listener.js for window 12
23:01:47 INFO - 1500962507019 Marionette TRACE 382 <- [1,1,null,{"sessionId":"094a21d5-980c-4400-b549-2fc76bee2efb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:47 INFO - 1500962507022 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"}]
23:01:47 INFO - 1500962507023 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"
23:01:47 INFO - 1500962507025 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"
23:01:47 INFO - 1500962507025 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"
23:01:47 INFO - 1500962507046 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"
23:01:47 INFO - 1500962507047 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"
23:01:47 INFO - 1500962507052 Marionette TRACE 382 <- [1,2,null,{}]
23:01:47 INFO - 1500962507060 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
23:01:47 INFO - 1500962507062 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a5a76cea-348a-4d6f-b28b-d3e42d6b5f4d","ELEMENT":"a5a76cea-348a-4d6f-b28b-d3e42d6b5f4d"}}]
23:01:47 INFO - 1500962507065 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"a5a76cea-348a-4d6f-b28b-d3e42d6b5f4d"}]
23:01:47 INFO - 1500962507310 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:47 INFO - 1500962507311 Marionette TRACE 382 <- [1,4,null,{}]
23:01:47 INFO - 1500962507313 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}]
23:01:47 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500962507315 Marionette TRACE 382 <- [1,5,null,{"value":null}]
23:01:47 INFO - 1500962507316 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
23:01:47 INFO - 1500962507318 Marionette TRACE 382 <- [1,6,null,{}]
23:01:47 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 347ms
23:01:47 INFO - TEST-START | test_click.py TestClick.test_input_file
23:01:47 INFO - 1500962507320 Marionette DEBUG Closed connection 382
23:01:47 INFO - 1500962507321 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49798
23:01:47 INFO - 1500962507322 Marionette DEBUG Closed connection 383
23:01:47 INFO - 1500962507322 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49799
23:01:47 INFO - 1500962507445 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:47 INFO - 1500962507449 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:47 INFO - 1500962507453 Marionette DEBUG Register listener.js for window 12
23:01:47 INFO - 1500962507463 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b897c95d-a9ee-4ed4-bd73-52d28b33f456","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:47 INFO - 1500962507467 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
23:01:47 INFO - 1500962507468 Marionette TRACE 384 <- [1,2,null,{}]
23:01:47 INFO - 1500962507471 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:47 INFO - 1500962507472 Marionette TRACE 384 <- [1,3,null,{}]
23:01:47 INFO - 1500962507484 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:47 INFO - 1500962507485 Marionette TRACE 384 <- [1,4,null,{}]
23:01:47 INFO - 1500962507487 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}]
23:01:47 INFO - TEST-START: test_click.py TestClick.test_input_file1500962507492 Marionette TRACE 384 <- [1,5,null,{"value":null}]
23:01:47 INFO - 1500962507494 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
23:01:47 INFO - 1500962507497 Marionette TRACE 384 <- [1,6,null,{}]
23:01:47 INFO - 1500962507500 Marionette DEBUG Closed connection 384
23:01:47 INFO - 1500962507501 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49800
23:01:47 INFO - 1500962507502 Marionette DEBUG Closed connection 385
23:01:47 INFO - 1500962507503 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49801
23:01:47 INFO - 1500962507504 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:47 INFO - 1500962507506 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:47 INFO - 1500962507509 Marionette DEBUG Register listener.js for window 12
23:01:47 INFO - 1500962507515 Marionette TRACE 386 <- [1,1,null,{"sessionId":"92cd8f54-bd16-42a6-8cd3-fbcb324f4899","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:47 INFO - 1500962507518 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
23:01:47 INFO - 1500962507520 Marionette TRACE 386 <- [1,2,null,{}]
23:01:47 INFO - 1500962507523 Marionette DEBUG Closed connection 386
23:01:47 INFO - 1500962507524 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49802
23:01:47 INFO - 1500962507525 Marionette DEBUG Closed connection 387
23:01:47 INFO - 1500962507526 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49803
23:01:47 INFO - 1500962507528 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:47 INFO - 1500962507529 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:47 INFO - 1500962507532 Marionette DEBUG Register listener.js for window 12
23:01:47 INFO - 1500962507537 Marionette TRACE 388 <- [1,1,null,{"sessionId":"59da3adc-9f32-43ee-83c5-be96da98af3c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:47 INFO - 1500962507540 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
23:01:47 INFO - 1500962507542 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"
23:01:47 INFO - 1500962507546 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"
23:01:47 INFO - 1500962507547 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"
23:01:47 INFO - 1500962507575 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:47 INFO - 1500962507577 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:47 INFO - 1500962507582 Marionette TRACE 388 <- [1,2,null,{}]
23:01:47 INFO - 1500962507596 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
23:01:47 INFO - 1500962507607 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2d31c956-3cdd-46f2-b0b7-10f6d8ed4e04","ELEMENT":"2d31c956-3cdd-46f2-b0b7-10f6d8ed4e04"}}]
23:01:47 INFO - 1500962507612 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2d31c956-3cdd-46f2-b0b7-10f6d8ed4e04"}]
23:01:47 INFO - 1500962507647 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
23:01:47 INFO - 1500962507649 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}]
23:01:47 INFO - TEST-END: test_click.py TestClick.test_input_file1500962507653 Marionette TRACE 388 <- [1,5,null,{"value":null}]
23:01:47 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 372ms
23:01:47 INFO - TEST-START | test_click.py TestClick.test_obscured_element
23:01:47 INFO - 1500962507688 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
23:01:47 INFO - 1500962507690 Marionette TRACE 388 <- [1,6,null,{}]
23:01:47 INFO - 1500962507696 Marionette DEBUG Closed connection 388
23:01:47 INFO - 1500962507697 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49804
23:01:47 INFO - 1500962507698 Marionette DEBUG Closed connection 389
23:01:47 INFO - 1500962507699 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49805
23:01:47 INFO - 1500962507700 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:47 INFO - 1500962507701 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:47 INFO - 1500962507705 Marionette DEBUG Register listener.js for window 12
23:01:47 INFO - 1500962507711 Marionette TRACE 390 <- [1,1,null,{"sessionId":"5780b057-d91b-40a4-9872-f0704131debe","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:47 INFO - 1500962507719 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
23:01:47 INFO - 1500962507721 Marionette TRACE 390 <- [1,2,null,{}]
23:01:47 INFO - 1500962507722 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:47 INFO - 1500962507722 Marionette TRACE 390 <- [1,3,null,{}]
23:01:47 INFO - 1500962507723 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:47 INFO - 1500962507724 Marionette TRACE 390 <- [1,4,null,{}]
23:01:47 INFO - 1500962507726 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}]
23:01:47 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500962507729 Marionette TRACE 390 <- [1,5,null,{"value":null}]
23:01:47 INFO - 1500962507730 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
23:01:47 INFO - 1500962507732 Marionette TRACE 390 <- [1,6,null,{}]
23:01:47 INFO - 1500962507734 Marionette DEBUG Closed connection 390
23:01:47 INFO - 1500962507735 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49806
23:01:47 INFO - 1500962507739 Marionette DEBUG Closed connection 391
23:01:47 INFO - 1500962507740 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49807
23:01:47 INFO - 1500962507748 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:47 INFO - 1500962507749 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:47 INFO - 1500962507752 Marionette DEBUG Register listener.js for window 12
23:01:47 INFO - 1500962507758 Marionette TRACE 392 <- [1,1,null,{"sessionId":"bdb5b8da-b42a-447f-a363-3fe245b2f340","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:47 INFO - 1500962507761 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
23:01:47 INFO - 1500962507763 Marionette TRACE 392 <- [1,2,null,{}]
23:01:47 INFO - 1500962507765 Marionette DEBUG Closed connection 392
23:01:47 INFO - 1500962507766 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49808
23:01:47 INFO - 1500962507767 Marionette DEBUG Closed connection 393
23:01:47 INFO - 1500962507768 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49809
23:01:47 INFO - 1500962507769 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:47 INFO - 1500962507770 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:47 INFO - 1500962507773 Marionette DEBUG Register listener.js for window 12
23:01:47 INFO - 1500962507777 Marionette TRACE 394 <- [1,1,null,{"sessionId":"94264b39-ae5d-4f9e-a641-f72b9e0cbd15","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:47 INFO - 1500962507779 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"}]
23:01:47 INFO - 1500962507781 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:47 INFO - 1500962507784 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:47 INFO - 1500962507784 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
23:01:47 INFO - 1500962507811 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"
23:01:47 INFO - 1500962507814 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"
23:01:47 INFO - 1500962507819 Marionette TRACE 394 <- [1,2,null,{}]
23:01:47 INFO - 1500962507827 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
23:01:47 INFO - 1500962507831 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7141ffc8-23e2-4338-a4d6-62eec28f85fa","ELEMENT":"7141ffc8-23e2-4338-a4d6-62eec28f85fa"}}]
23:01:47 INFO - 1500962507832 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
23:01:47 INFO - 1500962507834 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"de0f348e-6f0b-4ad4-85fb-79dea395ce52","ELEMENT":"de0f348e-6f0b-4ad4-85fb-79dea395ce52"}}]
23:01:47 INFO - 1500962507836 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7141ffc8-23e2-4338-a4d6-62eec28f85fa"}]
23:01:48 INFO - 1500962508085 Marionette DEBUG Canceled page load listener because no navigation has been detected
23:01:48 INFO - 1500962508085 Marionette TRACE 394 <- [1,5,null,{}]
23:01:48 INFO - 1500962508087 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"de0f348e-6f0b-4ad4-85fb-79dea395ce52"}]
23:01:48 INFO - 1500962508106 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
23:01:48 INFO - 1500962508107 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
23:01:48 INFO - 1500962508111 Marionette TRACE 394 <- [1,7,null,{"value":false}]
23:01:48 INFO - 1500962508112 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}]
23:01:48 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500962508115 Marionette TRACE 394 <- [1,8,null,{"value":null}]
23:01:48 INFO - 1500962508117 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
23:01:48 INFO - 1500962508119 Marionette TRACE 394 <- [1,9,null,{}]
23:01:48 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 427ms
23:01:48 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
23:01:48 INFO - 1500962508122 Marionette DEBUG Closed connection 394
23:01:48 INFO - 1500962508124 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49810
23:01:48 INFO - 1500962508125 Marionette DEBUG Closed connection 395
23:01:48 INFO - 1500962508125 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49811
23:01:48 INFO - 1500962508127 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:48 INFO - 1500962508127 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:48 INFO - 1500962508130 Marionette DEBUG Register listener.js for window 12
23:01:48 INFO - 1500962508133 Marionette TRACE 396 <- [1,1,null,{"sessionId":"2b044bc9-82b9-4463-8e17-c13e3fef2d2a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:48 INFO - 1500962508136 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
23:01:48 INFO - 1500962508136 Marionette TRACE 396 <- [1,2,null,{}]
23:01:48 INFO - 1500962508137 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
23:01:48 INFO - 1500962508138 Marionette TRACE 396 <- [1,3,null,{}]
23:01:48 INFO - 1500962508139 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
23:01:48 INFO - 1500962508140 Marionette TRACE 396 <- [1,4,null,{}]
23:01:48 INFO - 1500962508141 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}]
23:01:48 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500962508145 Marionette TRACE 396 <- [1,5,null,{"value":null}]
23:01:48 INFO - 1500962508146 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
23:01:48 INFO - 1500962508147 Marionette TRACE 396 <- [1,6,null,{}]
23:01:48 INFO - 1500962508149 Marionette DEBUG Closed connection 396
23:01:48 INFO - 1500962508150 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49812
23:01:48 INFO - 1500962508151 Marionette DEBUG Closed connection 397
23:01:48 INFO - 1500962508152 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49813
23:01:48 INFO - 1500962508153 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
23:01:48 INFO - 1500962508154 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:48 INFO - 1500962508156 Marionette DEBUG Register listener.js for window 12
23:01:48 INFO - 1500962508160 Marionette TRACE 398 <- [1,1,null,{"sessionId":"61eb6bd8-c358-4362-bde9-14b7cc805dc7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:48 INFO - 1500962508162 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
23:01:48 INFO - 1500962508164 Marionette TRACE 398 <- [1,2,null,{}]
23:01:48 INFO - 1500962508168 Marionette DEBUG Closed connection 398
23:01:48 INFO - 1500962508168 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49814
23:01:48 INFO - 1500962508169 Marionette DEBUG Closed connection 399
23:01:48 INFO - 1500962508170 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49815
23:01:48 INFO - 1500962508172 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
23:01:48 INFO - 1500962508172 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}
23:01:48 INFO - 1500962508175 Marionette DEBUG Register listener.js for window 12
23:01:48 INFO - 1500962508179 Marionette TRACE 400 <- [1,1,null,{"sessionId":"31db04c7-a710-4f84-a6e9-42498d7fc836","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3380,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-205.001\\AppData\\Local\\Temp\\tmp4wzcc9.mozrunner","moz:accessibilityChecks":false}}]
23:01:48 INFO - 1500962508181 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"}]
23:01:48 INFO - 1500962508182 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"
23:01:48 INFO - 1500962508185 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"
23:01:48 INFO - 1500962508186 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"
23:01:48 INFO - 1500962508201 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"
23:01:48 INFO - 1500962508205 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"
23:01:48 INFO - 1500962508215 Marionette TRACE 400 <- [1,2,null,{}]
23:01:48 INFO - 1500962508218 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
23:01:48 INFO - 1500962508221 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9451cf9b-63ce-4075-8165-67c456ca3fd9","ELEMENT":"9451cf9b-63ce-4075-8165-67c456ca3fd9"}}]
23:01:48 INFO - 1500962508222 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9451cf9b-63ce-4075-8165-67c456ca3fd9"}]
23:01:48 INFO - 1500962508254 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
23:01:48 INFO - 1500962508256 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9451cf9b-63ce-4075-8165-67c456ca3fd9"}]
23:01:48 INFO - 1500962508277 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element