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]
04:53:26 INFO - 1499860406651 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}]
04:53:26 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499860406654 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:53:26 INFO - 1499860406655 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:53:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 130ms
04:53:26 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:53:26 INFO - 1499860406656 Marionette TRACE 352 <- [1,6,null,{}]
04:53:26 INFO - 1499860406658 Marionette DEBUG Closed connection 352
04:53:26 INFO - 1499860406659 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49761
04:53:26 INFO - 1499860406659 Marionette DEBUG Closed connection 353
04:53:26 INFO - 1499860406660 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49762
04:53:26 INFO - 1499860406661 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:26 INFO - 1499860406662 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:26 INFO - 1499860406664 Marionette DEBUG Register listener.js for window 15
04:53:26 INFO - 1499860406667 Marionette TRACE 354 <- [1,1,null,{"sessionId":"5f41fe6e-10fe-470b-a3cf-3fc87780da7d","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:26 INFO - 1499860406669 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:53:26 INFO - 1499860406669 Marionette TRACE 354 <- [1,2,null,{}]
04:53:26 INFO - 1499860406670 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:53:26 INFO - 1499860406670 Marionette TRACE 354 <- [1,3,null,{}]
04:53:26 INFO - 1499860406671 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:53:26 INFO - 1499860406672 Marionette TRACE 354 <- [1,4,null,{}]
04:53:26 INFO - 1499860406673 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}]
04:53:26 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499860406675 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:53:26 INFO - 1499860406676 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:53:26 INFO - 1499860406677 Marionette TRACE 354 <- [1,6,null,{}]
04:53:26 INFO - 1499860406679 Marionette DEBUG Closed connection 354
04:53:26 INFO - 1499860406680 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49763
04:53:26 INFO - 1499860406680 Marionette DEBUG Closed connection 355
04:53:26 INFO - 1499860406681 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49764
04:53:26 INFO - 1499860406682 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:26 INFO - 1499860406682 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:26 INFO - 1499860406685 Marionette DEBUG Register listener.js for window 15
04:53:26 INFO - 1499860406687 Marionette TRACE 356 <- [1,1,null,{"sessionId":"c0eaa3e3-854c-4cbc-8bee-c28573b63d2b","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:26 INFO - 1499860406689 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:53:26 INFO - 1499860406690 Marionette TRACE 356 <- [1,2,null,{}]
04:53:26 INFO - 1499860406692 Marionette DEBUG Closed connection 356
04:53:26 INFO - 1499860406693 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49765
04:53:26 INFO - 1499860406693 Marionette DEBUG Closed connection 357
04:53:26 INFO - 1499860406694 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49766
04:53:26 INFO - 1499860406695 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:53:26 INFO - 1499860406696 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:26 INFO - 1499860406698 Marionette DEBUG Register listener.js for window 15
04:53:26 INFO - 1499860406701 Marionette TRACE 358 <- [1,1,null,{"sessionId":"016c2d0c-23ca-42a4-bfa0-86cbff602ebf","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:26 INFO - 1499860406702 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49324/clicks.html"}]
04:53:26 INFO - 1499860406704 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/hidden.html"
04:53:26 INFO - 1499860406708 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/hidden.html"
04:53:26 INFO - 1499860406708 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/hidden.html"
04:53:26 INFO - 1499860406727 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49324/clicks.html"
04:53:26 INFO - 1499860406729 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49324/clicks.html"
04:53:26 INFO - 1499860406733 Marionette TRACE 358 <- [1,2,null,{}]
04:53:26 INFO - 1499860406740 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:53:26 INFO - 1499860406742 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b02375fc-ae9f-4ea0-a34a-269a86810a8b","ELEMENT":"b02375fc-ae9f-4ea0-a34a-269a86810a8b"}}]
04:53:26 INFO - 1499860406743 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"b02375fc-ae9f-4ea0-a34a-269a86810a8b"}]
04:53:26 INFO - 1499860406779 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/clicks.html"
04:53:26 INFO - 1499860406785 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/clicks.html"
04:53:26 INFO - 1499860406786 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/clicks.html"
04:53:26 INFO - 1499860406814 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49324/test.html"
04:53:26 INFO - 1499860406817 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49324/test.html"
04:53:26 INFO - 1499860406820 Marionette TRACE 358 <- [1,4,null,{}]
04:53:26 INFO - 1499860406827 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:53:26 INFO - 1499860406829 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"090d11e5-0b39-4169-8ae8-e9d7a828aad2","ELEMENT":"090d11e5-0b39-4169-8ae8-e9d7a828aad2"}}]
04:53:26 INFO - 1499860406830 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:53:26 INFO - 1499860406830 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:53:26 INFO - 1499860406831 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}]
04:53:26 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499860406833 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:53:26 INFO - 1499860406834 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:53:26 INFO - 1499860406836 Marionette TRACE 358 <- [1,8,null,{}]
04:53:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 180ms
04:53:26 INFO - TEST-START | test_click.py TestClick.test_container_element
04:53:26 INFO - 1499860406839 Marionette DEBUG Closed connection 358
04:53:26 INFO - 1499860406840 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49769
04:53:26 INFO - 1499860406841 Marionette DEBUG Closed connection 359
04:53:26 INFO - 1499860406841 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49770
04:53:26 INFO - 1499860406842 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:26 INFO - 1499860406843 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:26 INFO - 1499860406845 Marionette DEBUG Register listener.js for window 15
04:53:26 INFO - 1499860406848 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3e5f4a89-b857-4626-ba99-6199ecd027b5","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:26 INFO - 1499860406850 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:53:26 INFO - 1499860406850 Marionette TRACE 360 <- [1,2,null,{}]
04:53:26 INFO - 1499860406851 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:53:26 INFO - 1499860406851 Marionette TRACE 360 <- [1,3,null,{}]
04:53:26 INFO - 1499860406853 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:53:26 INFO - 1499860406853 Marionette TRACE 360 <- [1,4,null,{}]
04:53:26 INFO - 1499860406855 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}]
04:53:26 INFO - TEST-START: test_click.py TestClick.test_container_element1499860406857 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:53:26 INFO - 1499860406858 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:53:26 INFO - 1499860406859 Marionette TRACE 360 <- [1,6,null,{}]
04:53:26 INFO - 1499860406861 Marionette DEBUG Closed connection 360
04:53:26 INFO - 1499860406861 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49771
04:53:26 INFO - 1499860406862 Marionette DEBUG Closed connection 361
04:53:26 INFO - 1499860406863 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49772
04:53:26 INFO - 1499860406864 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:26 INFO - 1499860406870 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:26 INFO - 1499860406872 Marionette DEBUG Register listener.js for window 15
04:53:26 INFO - 1499860406876 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ac23e14c-e652-4de9-b8ce-e0c556d4a0fe","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:26 INFO - 1499860406878 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:53:26 INFO - 1499860406879 Marionette TRACE 362 <- [1,2,null,{}]
04:53:26 INFO - 1499860406880 Marionette DEBUG Closed connection 362
04:53:26 INFO - 1499860406881 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49773
04:53:26 INFO - 1499860406881 Marionette DEBUG Closed connection 363
04:53:26 INFO - 1499860406882 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49774
04:53:26 INFO - 1499860406883 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:53:26 INFO - 1499860406883 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:26 INFO - 1499860406885 Marionette DEBUG Register listener.js for window 15
04:53:26 INFO - 1499860406889 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f8119242-388c-43e1-91b5-73c7992cb318","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:26 INFO - 1499860406891 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"}]
04:53:26 INFO - 1499860406893 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49324/test.html"
04:53:26 INFO - 1499860406894 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49324/test.html"
04:53:26 INFO - 1499860406895 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49324/test.html"
04:53:26 INFO - 1499860406915 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"
04:53:26 INFO - 1499860406918 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"
04:53:26 INFO - 1499860406926 Marionette TRACE 364 <- [1,2,null,{}]
04:53:26 INFO - 1499860406928 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:53:26 INFO - 1499860406930 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5736c171-2760-4d28-b061-bc89e59da301","ELEMENT":"5736c171-2760-4d28-b061-bc89e59da301"}}]
04:53:26 INFO - 1499860406930 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5736c171-2760-4d28-b061-bc89e59da301"}]
04:53:27 INFO - 1499860407167 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:53:27 INFO - 1499860407167 Marionette TRACE 364 <- [1,4,null,{}]
04:53:27 INFO - 1499860407168 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5736c171-2760-4d28-b061-bc89e59da301","name":"selected"}]
04:53:27 INFO - 1499860407172 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:53:27 INFO - 1499860407174 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}]
04:53:27 INFO - TEST-END: test_click.py TestClick.test_container_element1499860407176 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:53:27 INFO - 1499860407177 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:53:27 INFO - 1499860407178 Marionette TRACE 364 <- [1,7,null,{}]
04:53:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 342ms
04:53:27 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:53:27 INFO - 1499860407179 Marionette DEBUG Closed connection 364
04:53:27 INFO - 1499860407181 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49775
04:53:27 INFO - 1499860407181 Marionette DEBUG Closed connection 365
04:53:27 INFO - 1499860407182 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49776
04:53:27 INFO - 1499860407183 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:27 INFO - 1499860407184 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407186 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407188 Marionette TRACE 366 <- [1,1,null,{"sessionId":"12c9e466-00b2-4a12-b29b-f55814836c61","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407190 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:53:27 INFO - 1499860407191 Marionette TRACE 366 <- [1,2,null,{}]
04:53:27 INFO - 1499860407192 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:53:27 INFO - 1499860407192 Marionette TRACE 366 <- [1,3,null,{}]
04:53:27 INFO - 1499860407193 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:53:27 INFO - 1499860407193 Marionette TRACE 366 <- [1,4,null,{}]
04:53:27 INFO - 1499860407194 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}]
04:53:27 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499860407196 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:53:27 INFO - 1499860407197 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:53:27 INFO - 1499860407198 Marionette TRACE 366 <- [1,6,null,{}]
04:53:27 INFO - 1499860407200 Marionette DEBUG Closed connection 366
04:53:27 INFO - 1499860407200 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49777
04:53:27 INFO - 1499860407201 Marionette DEBUG Closed connection 367
04:53:27 INFO - 1499860407202 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49778
04:53:27 INFO - 1499860407203 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:27 INFO - 1499860407203 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407205 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407208 Marionette TRACE 368 <- [1,1,null,{"sessionId":"72f8bbb9-7f7e-436b-9061-89bef53e2593","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407209 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:53:27 INFO - 1499860407210 Marionette TRACE 368 <- [1,2,null,{}]
04:53:27 INFO - 1499860407212 Marionette DEBUG Closed connection 368
04:53:27 INFO - 1499860407212 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49779
04:53:27 INFO - 1499860407213 Marionette DEBUG Closed connection 369
04:53:27 INFO - 1499860407214 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49780
04:53:27 INFO - 1499860407215 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:53:27 INFO - 1499860407215 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407217 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407219 Marionette TRACE 370 <- [1,1,null,{"sessionId":"111a74b0-48ba-4784-8cd3-36c6721f3095","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407221 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"}]
04:53:27 INFO - 1499860407223 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"
04:53:27 INFO - 1499860407225 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"
04:53:27 INFO - 1499860407225 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"
04:53:27 INFO - 1499860407235 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"
04:53:27 INFO - 1499860407244 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"
04:53:27 INFO - 1499860407247 Marionette TRACE 370 <- [1,2,null,{}]
04:53:27 INFO - 1499860407248 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:53:27 INFO - 1499860407250 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f76ab446-b9a4-4db2-9a34-6ef737b56dcb","ELEMENT":"f76ab446-b9a4-4db2-9a34-6ef737b56dcb"}}]
04:53:27 INFO - 1499860407250 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"f76ab446-b9a4-4db2-9a34-6ef737b56dcb"}]
04:53:27 INFO - 1499860407500 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:53:27 INFO - 1499860407500 Marionette TRACE 370 <- [1,4,null,{}]
04:53:27 INFO - 1499860407501 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"f76ab446-b9a4-4db2-9a34-6ef737b56dcb","name":"selected"}]
04:53:27 INFO - 1499860407505 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:53:27 INFO - 1499860407507 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}]
04:53:27 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499860407509 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:53:27 INFO - 1499860407509 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:53:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 331ms
04:53:27 INFO - 1499860407511 Marionette TRACE 370 <- [1,7,null,{}]
04:53:27 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:53:27 INFO - 1499860407512 Marionette DEBUG Closed connection 370
04:53:27 INFO - 1499860407514 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49781
04:53:27 INFO - 1499860407514 Marionette DEBUG Closed connection 371
04:53:27 INFO - 1499860407515 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49782
04:53:27 INFO - 1499860407516 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:27 INFO - 1499860407516 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407519 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407521 Marionette TRACE 372 <- [1,1,null,{"sessionId":"f055f254-8b37-4cdd-9414-8b7a8c25cfb6","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407523 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:53:27 INFO - 1499860407523 Marionette TRACE 372 <- [1,2,null,{}]
04:53:27 INFO - 1499860407524 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:53:27 INFO - 1499860407524 Marionette TRACE 372 <- [1,3,null,{}]
04:53:27 INFO - 1499860407525 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:53:27 INFO - 1499860407525 Marionette TRACE 372 <- [1,4,null,{}]
04:53:27 INFO - 1499860407527 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}]
04:53:27 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499860407529 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:53:27 INFO - 1499860407530 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:53:27 INFO - 1499860407531 Marionette TRACE 372 <- [1,6,null,{}]
04:53:27 INFO - 1499860407533 Marionette DEBUG Closed connection 372
04:53:27 INFO - 1499860407533 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49783
04:53:27 INFO - 1499860407534 Marionette DEBUG Closed connection 373
04:53:27 INFO - 1499860407534 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49784
04:53:27 INFO - 1499860407536 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:27 INFO - 1499860407537 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407539 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407541 Marionette TRACE 374 <- [1,1,null,{"sessionId":"78a1ee87-21f6-4c81-9512-9524d848f16c","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407543 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:53:27 INFO - 1499860407544 Marionette TRACE 374 <- [1,2,null,{}]
04:53:27 INFO - 1499860407545 Marionette DEBUG Closed connection 374
04:53:27 INFO - 1499860407546 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49785
04:53:27 INFO - 1499860407547 Marionette DEBUG Closed connection 375
04:53:27 INFO - 1499860407548 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49786
04:53:27 INFO - 1499860407549 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:53:27 INFO - 1499860407549 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407551 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407554 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8efdeef2-2ba5-4c04-ac84-7f037dca8b3e","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407556 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"}]
04:53:27 INFO - 1499860407557 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"
04:53:27 INFO - 1499860407559 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"
04:53:27 INFO - 1499860407559 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"
04:53:27 INFO - 1499860407568 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"
04:53:27 INFO - 1499860407571 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"
04:53:27 INFO - 1499860407579 Marionette TRACE 376 <- [1,2,null,{}]
04:53:27 INFO - 1499860407581 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:53:27 INFO - 1499860407583 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1dd17075-b8a8-4907-841f-a397eb5617ec","ELEMENT":"1dd17075-b8a8-4907-841f-a397eb5617ec"}}]
04:53:27 INFO - 1499860407584 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"1dd17075-b8a8-4907-841f-a397eb5617ec"}]
04:53:27 INFO - 1499860407819 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:53:27 INFO - 1499860407820 Marionette TRACE 376 <- [1,4,null,{}]
04:53:27 INFO - 1499860407821 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}]
04:53:27 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499860407824 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:53:27 INFO - 1499860407825 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:53:27 INFO - 1499860407826 Marionette TRACE 376 <- [1,6,null,{}]
04:53:27 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 314ms
04:53:27 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:53:27 INFO - 1499860407827 Marionette DEBUG Closed connection 376
04:53:27 INFO - 1499860407828 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49787
04:53:27 INFO - 1499860407829 Marionette DEBUG Closed connection 377
04:53:27 INFO - 1499860407830 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49788
04:53:27 INFO - 1499860407830 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:27 INFO - 1499860407831 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407833 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407835 Marionette TRACE 378 <- [1,1,null,{"sessionId":"a305466b-c446-43a9-b218-9ac27ff4f5ff","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407837 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:53:27 INFO - 1499860407838 Marionette TRACE 378 <- [1,2,null,{}]
04:53:27 INFO - 1499860407838 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:53:27 INFO - 1499860407839 Marionette TRACE 378 <- [1,3,null,{}]
04:53:27 INFO - 1499860407839 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:53:27 INFO - 1499860407840 Marionette TRACE 378 <- [1,4,null,{}]
04:53:27 INFO - 1499860407841 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}]
04:53:27 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499860407843 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:53:27 INFO - 1499860407844 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:53:27 INFO - 1499860407845 Marionette TRACE 378 <- [1,6,null,{}]
04:53:27 INFO - 1499860407846 Marionette DEBUG Closed connection 378
04:53:27 INFO - 1499860407847 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49789
04:53:27 INFO - 1499860407848 Marionette DEBUG Closed connection 379
04:53:27 INFO - 1499860407848 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49790
04:53:27 INFO - 1499860407849 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:27 INFO - 1499860407850 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407852 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407860 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0972f855-f66d-491c-b2d7-ec29f41cb609","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407862 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:53:27 INFO - 1499860407864 Marionette TRACE 380 <- [1,2,null,{}]
04:53:27 INFO - 1499860407866 Marionette DEBUG Closed connection 380
04:53:27 INFO - 1499860407866 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49791
04:53:27 INFO - 1499860407867 Marionette DEBUG Closed connection 381
04:53:27 INFO - 1499860407867 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49792
04:53:27 INFO - 1499860407868 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:53:27 INFO - 1499860407869 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:27 INFO - 1499860407871 Marionette DEBUG Register listener.js for window 15
04:53:27 INFO - 1499860407874 Marionette TRACE 382 <- [1,1,null,{"sessionId":"5d18445b-19d0-4329-8df1-5d9340122a69","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:27 INFO - 1499860407875 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"}]
04:53:27 INFO - 1499860407877 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"
04:53:27 INFO - 1499860407879 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"
04:53:27 INFO - 1499860407879 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"
04:53:27 INFO - 1499860407895 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"
04:53:27 INFO - 1499860407898 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"
04:53:27 INFO - 1499860407900 Marionette TRACE 382 <- [1,2,null,{}]
04:53:27 INFO - 1499860407901 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:53:27 INFO - 1499860407909 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d9209053-a039-494c-bbea-7635707b0970","ELEMENT":"d9209053-a039-494c-bbea-7635707b0970"}}]
04:53:27 INFO - 1499860407910 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d9209053-a039-494c-bbea-7635707b0970"}]
04:53:28 INFO - 1499860408147 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:53:28 INFO - 1499860408148 Marionette TRACE 382 <- [1,4,null,{}]
04:53:28 INFO - 1499860408150 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}]
04:53:28 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499860408152 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:53:28 INFO - 1499860408153 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:53:28 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 327ms
04:53:28 INFO - TEST-START | test_click.py TestClick.test_input_file
04:53:28 INFO - 1499860408154 Marionette TRACE 382 <- [1,6,null,{}]
04:53:28 INFO - 1499860408155 Marionette DEBUG Closed connection 382
04:53:28 INFO - 1499860408156 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49793
04:53:28 INFO - 1499860408157 Marionette DEBUG Closed connection 383
04:53:28 INFO - 1499860408158 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49794
04:53:28 INFO - 1499860408158 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:28 INFO - 1499860408159 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408161 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408163 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e28c8c5f-ba30-4efe-b5cd-e8324c7f7458","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408165 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:53:28 INFO - 1499860408165 Marionette TRACE 384 <- [1,2,null,{}]
04:53:28 INFO - 1499860408166 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:53:28 INFO - 1499860408166 Marionette TRACE 384 <- [1,3,null,{}]
04:53:28 INFO - 1499860408167 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:53:28 INFO - 1499860408167 Marionette TRACE 384 <- [1,4,null,{}]
04:53:28 INFO - 1499860408169 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}]
04:53:28 INFO - TEST-START: test_click.py TestClick.test_input_file1499860408171 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:53:28 INFO - 1499860408171 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:53:28 INFO - 1499860408172 Marionette TRACE 384 <- [1,6,null,{}]
04:53:28 INFO - 1499860408174 Marionette DEBUG Closed connection 384
04:53:28 INFO - 1499860408175 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49795
04:53:28 INFO - 1499860408175 Marionette DEBUG Closed connection 385
04:53:28 INFO - 1499860408176 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49796
04:53:28 INFO - 1499860408177 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:28 INFO - 1499860408178 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408179 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408182 Marionette TRACE 386 <- [1,1,null,{"sessionId":"772aa418-65d6-4c67-847f-afe12c4e3ebb","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408184 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:53:28 INFO - 1499860408185 Marionette TRACE 386 <- [1,2,null,{}]
04:53:28 INFO - 1499860408187 Marionette DEBUG Closed connection 386
04:53:28 INFO - 1499860408187 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49797
04:53:28 INFO - 1499860408188 Marionette DEBUG Closed connection 387
04:53:28 INFO - 1499860408188 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49798
04:53:28 INFO - 1499860408189 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:53:28 INFO - 1499860408190 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408192 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408194 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2fa53164-6a9b-4ca1-b934-ea039abc3b89","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408196 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:53:28 INFO - 1499860408197 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"
04:53:28 INFO - 1499860408199 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"
04:53:28 INFO - 1499860408199 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"
04:53:28 INFO - 1499860408215 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:53:28 INFO - 1499860408217 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:53:28 INFO - 1499860408220 Marionette TRACE 388 <- [1,2,null,{}]
04:53:28 INFO - 1499860408226 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:53:28 INFO - 1499860408229 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"79e47d85-25a6-4b56-95cf-355f3efa1f90","ELEMENT":"79e47d85-25a6-4b56-95cf-355f3efa1f90"}}]
04:53:28 INFO - 1499860408229 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"79e47d85-25a6-4b56-95cf-355f3efa1f90"}]
04:53:28 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 113ms
04:53:28 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:53:28 INFO - 1499860408261 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]
04:53:28 INFO - 1499860408263 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}]
04:53:28 INFO - TEST-END: test_click.py TestClick.test_input_file1499860408265 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:53:28 INFO - 1499860408266 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:53:28 INFO - 1499860408267 Marionette TRACE 388 <- [1,6,null,{}]
04:53:28 INFO - 1499860408269 Marionette DEBUG Closed connection 388
04:53:28 INFO - 1499860408270 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49799
04:53:28 INFO - 1499860408270 Marionette DEBUG Closed connection 389
04:53:28 INFO - 1499860408271 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49800
04:53:28 INFO - 1499860408272 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:28 INFO - 1499860408273 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408275 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408277 Marionette TRACE 390 <- [1,1,null,{"sessionId":"e154d003-f53a-4814-9569-f9e416c78afa","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408279 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:53:28 INFO - 1499860408279 Marionette TRACE 390 <- [1,2,null,{}]
04:53:28 INFO - 1499860408280 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:53:28 INFO - 1499860408281 Marionette TRACE 390 <- [1,3,null,{}]
04:53:28 INFO - 1499860408281 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:53:28 INFO - 1499860408282 Marionette TRACE 390 <- [1,4,null,{}]
04:53:28 INFO - 1499860408283 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}]
04:53:28 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499860408285 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:53:28 INFO - 1499860408286 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:53:28 INFO - 1499860408287 Marionette TRACE 390 <- [1,6,null,{}]
04:53:28 INFO - 1499860408288 Marionette DEBUG Closed connection 390
04:53:28 INFO - 1499860408289 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49801
04:53:28 INFO - 1499860408289 Marionette DEBUG Closed connection 391
04:53:28 INFO - 1499860408290 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49802
04:53:28 INFO - 1499860408291 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:28 INFO - 1499860408291 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408293 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408296 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d2a2768f-8d1b-4bf9-80c3-02258f0f552e","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408297 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:53:28 INFO - 1499860408298 Marionette TRACE 392 <- [1,2,null,{}]
04:53:28 INFO - 1499860408300 Marionette DEBUG Closed connection 392
04:53:28 INFO - 1499860408301 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49803
04:53:28 INFO - 1499860408301 Marionette DEBUG Closed connection 393
04:53:28 INFO - 1499860408302 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49804
04:53:28 INFO - 1499860408303 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:53:28 INFO - 1499860408303 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408305 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408307 Marionette TRACE 394 <- [1,1,null,{"sessionId":"d39e5ba6-7b62-4057-b5ed-fac8076eb184","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408309 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"}]
04:53:28 INFO - 1499860408310 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:53:28 INFO - 1499860408312 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:53:28 INFO - 1499860408312 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:53:28 INFO - 1499860408329 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"
04:53:28 INFO - 1499860408330 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"
04:53:28 INFO - 1499860408333 Marionette TRACE 394 <- [1,2,null,{}]
04:53:28 INFO - 1499860408334 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:53:28 INFO - 1499860408341 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c0ab8095-1f5d-4b16-aa13-e39aad431b9a","ELEMENT":"c0ab8095-1f5d-4b16-aa13-e39aad431b9a"}}]
04:53:28 INFO - 1499860408344 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:53:28 INFO - 1499860408345 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ff7fe40e-1a2c-46ac-a991-adc9779c2e37","ELEMENT":"ff7fe40e-1a2c-46ac-a991-adc9779c2e37"}}]
04:53:28 INFO - 1499860408346 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"c0ab8095-1f5d-4b16-aa13-e39aad431b9a"}]
04:53:28 INFO - 1499860408582 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:53:28 INFO - 1499860408582 Marionette TRACE 394 <- [1,5,null,{}]
04:53:28 INFO - 1499860408583 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"ff7fe40e-1a2c-46ac-a991-adc9779c2e37"}]
04:53:28 INFO - 1499860408596 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]
04:53:28 INFO - 1499860408598 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:53:28 INFO - 1499860408600 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:53:28 INFO - 1499860408602 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}]
04:53:28 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499860408604 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:53:28 INFO - 1499860408605 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:53:28 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 338ms
04:53:28 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:53:28 INFO - 1499860408606 Marionette TRACE 394 <- [1,9,null,{}]
04:53:28 INFO - 1499860408608 Marionette DEBUG Closed connection 394
04:53:28 INFO - 1499860408608 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49805
04:53:28 INFO - 1499860408609 Marionette DEBUG Closed connection 395
04:53:28 INFO - 1499860408610 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49806
04:53:28 INFO - 1499860408611 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:28 INFO - 1499860408611 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408613 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408616 Marionette TRACE 396 <- [1,1,null,{"sessionId":"82f598ed-6d6d-4ab8-9663-06a22bde1049","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408617 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:53:28 INFO - 1499860408618 Marionette TRACE 396 <- [1,2,null,{}]
04:53:28 INFO - 1499860408619 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:53:28 INFO - 1499860408619 Marionette TRACE 396 <- [1,3,null,{}]
04:53:28 INFO - 1499860408620 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:53:28 INFO - 1499860408620 Marionette TRACE 396 <- [1,4,null,{}]
04:53:28 INFO - 1499860408622 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}]
04:53:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499860408624 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:53:28 INFO - 1499860408625 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:53:28 INFO - 1499860408626 Marionette TRACE 396 <- [1,6,null,{}]
04:53:28 INFO - 1499860408628 Marionette DEBUG Closed connection 396
04:53:28 INFO - 1499860408628 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49807
04:53:28 INFO - 1499860408629 Marionette DEBUG Closed connection 397
04:53:28 INFO - 1499860408629 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49808
04:53:28 INFO - 1499860408630 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:53:28 INFO - 1499860408631 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408633 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408635 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5ab0e23f-110f-443f-9ff6-856036e164fe","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408637 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:53:28 INFO - 1499860408638 Marionette TRACE 398 <- [1,2,null,{}]
04:53:28 INFO - 1499860408639 Marionette DEBUG Closed connection 398
04:53:28 INFO - 1499860408640 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49809
04:53:28 INFO - 1499860408640 Marionette DEBUG Closed connection 399
04:53:28 INFO - 1499860408641 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49810
04:53:28 INFO - 1499860408642 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:53:28 INFO - 1499860408643 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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}
04:53:28 INFO - 1499860408645 Marionette DEBUG Register listener.js for window 15
04:53:28 INFO - 1499860408653 Marionette TRACE 400 <- [1,1,null,{"sessionId":"7fb855cb-7d51-4870-852c-e87cb2773344","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":3436,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-094.001\\AppData\\Local\\Temp\\tmpnlnlxp.mozrunner","moz:accessibilityChecks":false}}]
04:53:28 INFO - 1499860408655 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"}]
04:53:28 INFO - 1499860408657 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"
04:53:28 INFO - 1499860408659 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"
04:53:28 INFO - 1499860408659 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"
04:53:28 INFO - 1499860408675 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"
04:53:28 INFO - 1499860408677 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"
04:53:28 INFO - 1499860408680 Marionette TRACE 400 <- [1,2,null,{}]
04:53:28 INFO - 1499860408681 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:53:28 INFO - 1499860408683 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2685c874-e3d9-4944-9602-9db947edf20d","ELEMENT":"2685c874-e3d9-4944-9602-9db947edf20d"}}]
04:53:28 INFO - 1499860408684 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2685c874-e3d9-4944-9602-9db947edf20d"}]
04:53:28 INFO - 1499860408709 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:53:28 INFO - 1499860408711 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"2685c874-e3d9-4944-9602-9db947edf20d"}]
04:53:28 INFO - 1499860408727 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element