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]
04:04:51 INFO - 1500721491909 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:04:51 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500721491911 Marionette TRACE 352 <- [1,5,null,{"value":null}]
04:04:51 INFO - 1500721491912 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
04:04:51 INFO - 1500721491913 Marionette TRACE 352 <- [1,6,null,{}]
04:04:51 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
04:04:51 INFO - 1500721491915 Marionette DEBUG Closed connection 352
04:04:51 INFO - 1500721491916 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49893
04:04:51 INFO - 1500721491917 Marionette DEBUG Closed connection 353
04:04:51 INFO - 1500721491918 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49894
04:04:51 INFO - 1500721491919 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:51 INFO - 1500721491920 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:51 INFO - 1500721491922 Marionette DEBUG Register listener.js for window 15
04:04:51 INFO - 1500721491926 Marionette TRACE 354 <- [1,1,null,{"sessionId":"da2c2144-4075-4af8-9340-417aed9735c1","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:51 INFO - 1500721491928 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
04:04:51 INFO - 1500721491929 Marionette TRACE 354 <- [1,2,null,{}]
04:04:51 INFO - 1500721491930 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:51 INFO - 1500721491930 Marionette TRACE 354 <- [1,3,null,{}]
04:04:51 INFO - 1500721491931 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:51 INFO - 1500721491932 Marionette TRACE 354 <- [1,4,null,{}]
04:04:51 INFO - 1500721491933 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:04:51 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500721491936 Marionette TRACE 354 <- [1,5,null,{"value":null}]
04:04:51 INFO - 1500721491937 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
04:04:51 INFO - 1500721491939 Marionette TRACE 354 <- [1,6,null,{}]
04:04:51 INFO - 1500721491941 Marionette DEBUG Closed connection 354
04:04:51 INFO - 1500721491941 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49895
04:04:51 INFO - 1500721491942 Marionette DEBUG Closed connection 355
04:04:51 INFO - 1500721491943 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49896
04:04:51 INFO - 1500721491944 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:51 INFO - 1500721491944 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:51 INFO - 1500721491947 Marionette DEBUG Register listener.js for window 15
04:04:51 INFO - 1500721491950 Marionette TRACE 356 <- [1,1,null,{"sessionId":"a7e24346-a72a-40f0-a73e-2e5b474d9d73","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:51 INFO - 1500721491952 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
04:04:51 INFO - 1500721491954 Marionette TRACE 356 <- [1,2,null,{}]
04:04:51 INFO - 1500721491956 Marionette DEBUG Closed connection 356
04:04:51 INFO - 1500721491956 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49897
04:04:51 INFO - 1500721491957 Marionette DEBUG Closed connection 357
04:04:51 INFO - 1500721491958 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49898
04:04:51 INFO - 1500721491959 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:51 INFO - 1500721491960 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:51 INFO - 1500721491962 Marionette DEBUG Register listener.js for window 15
04:04:51 INFO - 1500721491965 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b99c6ed4-79d2-4488-935b-62da339a0091","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:51 INFO - 1500721491968 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49462/clicks.html"}]
04:04:51 INFO - 1500721491969 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49462/hidden.html"
04:04:51 INFO - 1500721491976 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49462/hidden.html"
04:04:51 INFO - 1500721491976 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49462/hidden.html"
04:04:51 INFO - 1500721491994 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49462/clicks.html"
04:04:52 INFO - 1500721491999 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49462/clicks.html"
04:04:52 INFO - 1500721492009 Marionette TRACE 358 <- [1,2,null,{}]
04:04:52 INFO - 1500721492010 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
04:04:52 INFO - 1500721492012 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"65b19ed7-5153-4588-9404-7022eb340886","ELEMENT":"65b19ed7-5153-4588-9404-7022eb340886"}}]
04:04:52 INFO - 1500721492013 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"65b19ed7-5153-4588-9404-7022eb340886"}]
04:04:52 INFO - 1500721492064 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49462/clicks.html"
04:04:52 INFO - 1500721492073 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49462/clicks.html"
04:04:52 INFO - 1500721492073 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49462/clicks.html"
04:04:52 INFO - 1500721492095 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49462/test.html"
04:04:52 INFO - 1500721492096 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49462/test.html"
04:04:52 INFO - 1500721492099 Marionette TRACE 358 <- [1,4,null,{}]
04:04:52 INFO - 1500721492107 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
04:04:52 INFO - 1500721492110 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"06f2e22c-c35e-4aef-af2e-ac298d218663","ELEMENT":"06f2e22c-c35e-4aef-af2e-ac298d218663"}}]
04:04:52 INFO - 1500721492111 Marionette TRACE 358 -> [0,6,"getTitle",{}]
04:04:52 INFO - 1500721492111 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
04:04:52 INFO - 1500721492113 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:04:52 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500721492117 Marionette TRACE 358 <- [1,7,null,{"value":null}]
04:04:52 INFO - 1500721492118 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
04:04:52 INFO - 1500721492120 Marionette TRACE 358 <- [1,8,null,{}]
04:04:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 206ms
04:04:52 INFO - TEST-START | test_click.py TestClick.test_container_element
04:04:52 INFO - 1500721492123 Marionette DEBUG Closed connection 358
04:04:52 INFO - 1500721492124 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49901
04:04:52 INFO - 1500721492125 Marionette DEBUG Closed connection 359
04:04:52 INFO - 1500721492125 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49902
04:04:52 INFO - 1500721492126 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:52 INFO - 1500721492127 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492130 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492134 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3240d9bd-f79d-42ff-95d4-1db2766ec8ab","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492136 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
04:04:52 INFO - 1500721492136 Marionette TRACE 360 <- [1,2,null,{}]
04:04:52 INFO - 1500721492137 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:52 INFO - 1500721492138 Marionette TRACE 360 <- [1,3,null,{}]
04:04:52 INFO - 1500721492139 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:52 INFO - 1500721492139 Marionette TRACE 360 <- [1,4,null,{}]
04:04:52 INFO - 1500721492141 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:04:52 INFO - TEST-START: test_click.py TestClick.test_container_element1500721492143 Marionette TRACE 360 <- [1,5,null,{"value":null}]
04:04:52 INFO - 1500721492144 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
04:04:52 INFO - 1500721492146 Marionette TRACE 360 <- [1,6,null,{}]
04:04:52 INFO - 1500721492148 Marionette DEBUG Closed connection 360
04:04:52 INFO - 1500721492149 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49903
04:04:52 INFO - 1500721492150 Marionette DEBUG Closed connection 361
04:04:52 INFO - 1500721492150 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49904
04:04:52 INFO - 1500721492152 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:52 INFO - 1500721492152 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492155 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492159 Marionette TRACE 362 <- [1,1,null,{"sessionId":"34ced2aa-3d19-417d-a54f-b8fccc6d3fda","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492161 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
04:04:52 INFO - 1500721492162 Marionette TRACE 362 <- [1,2,null,{}]
04:04:52 INFO - 1500721492165 Marionette DEBUG Closed connection 362
04:04:52 INFO - 1500721492166 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49905
04:04:52 INFO - 1500721492167 Marionette DEBUG Closed connection 363
04:04:52 INFO - 1500721492167 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49906
04:04:52 INFO - 1500721492168 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:52 INFO - 1500721492170 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492173 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492177 Marionette TRACE 364 <- [1,1,null,{"sessionId":"991fd778-5ee1-4c33-9679-68e9cbed5590","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492179 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:04:52 INFO - 1500721492181 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49462/test.html"
04:04:52 INFO - 1500721492184 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49462/test.html"
04:04:52 INFO - 1500721492184 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49462/test.html"
04:04:52 INFO - 1500721492212 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:04:52 INFO - 1500721492213 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:04:52 INFO - 1500721492217 Marionette TRACE 364 <- [1,2,null,{}]
04:04:52 INFO - 1500721492223 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:04:52 INFO - 1500721492226 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b505a13e-376e-4944-bdf1-4db39b45e502","ELEMENT":"b505a13e-376e-4944-bdf1-4db39b45e502"}}]
04:04:52 INFO - 1500721492227 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b505a13e-376e-4944-bdf1-4db39b45e502"}]
04:04:52 INFO - 1500721492472 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:52 INFO - 1500721492473 Marionette TRACE 364 <- [1,4,null,{}]
04:04:52 INFO - 1500721492474 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b505a13e-376e-4944-bdf1-4db39b45e502","name":"selected"}]
04:04:52 INFO - 1500721492478 Marionette TRACE 364 <- [1,5,null,{"value":true}]
04:04:52 INFO - 1500721492480 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:04:52 INFO - TEST-END: test_click.py TestClick.test_container_element1500721492484 Marionette TRACE 364 <- [1,6,null,{"value":null}]
04:04:52 INFO - 1500721492485 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
04:04:52 INFO - 1500721492486 Marionette TRACE 364 <- [1,7,null,{}]
04:04:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 366ms
04:04:52 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
04:04:52 INFO - 1500721492489 Marionette DEBUG Closed connection 364
04:04:52 INFO - 1500721492490 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49907
04:04:52 INFO - 1500721492491 Marionette DEBUG Closed connection 365
04:04:52 INFO - 1500721492491 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49908
04:04:52 INFO - 1500721492492 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:52 INFO - 1500721492493 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492496 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492499 Marionette TRACE 366 <- [1,1,null,{"sessionId":"99e48e63-2f37-4e50-abb4-88b4840f88fa","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492502 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
04:04:52 INFO - 1500721492503 Marionette TRACE 366 <- [1,2,null,{}]
04:04:52 INFO - 1500721492503 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:52 INFO - 1500721492504 Marionette TRACE 366 <- [1,3,null,{}]
04:04:52 INFO - 1500721492505 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:52 INFO - 1500721492506 Marionette TRACE 366 <- [1,4,null,{}]
04:04:52 INFO - 1500721492508 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:04:52 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500721492511 Marionette TRACE 366 <- [1,5,null,{"value":null}]
04:04:52 INFO - 1500721492512 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
04:04:52 INFO - 1500721492513 Marionette TRACE 366 <- [1,6,null,{}]
04:04:52 INFO - 1500721492515 Marionette DEBUG Closed connection 366
04:04:52 INFO - 1500721492516 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49909
04:04:52 INFO - 1500721492517 Marionette DEBUG Closed connection 367
04:04:52 INFO - 1500721492517 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49910
04:04:52 INFO - 1500721492519 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:52 INFO - 1500721492519 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492522 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492533 Marionette TRACE 368 <- [1,1,null,{"sessionId":"4247ea4a-9c53-4adc-8d9a-bd2e04edf29b","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492536 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
04:04:52 INFO - 1500721492537 Marionette TRACE 368 <- [1,2,null,{}]
04:04:52 INFO - 1500721492539 Marionette DEBUG Closed connection 368
04:04:52 INFO - 1500721492540 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49911
04:04:52 INFO - 1500721492541 Marionette DEBUG Closed connection 369
04:04:52 INFO - 1500721492541 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49912
04:04:52 INFO - 1500721492542 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:52 INFO - 1500721492543 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492546 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492549 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ec3d5461-9319-4a9f-8f58-ec81a9f6daad","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492551 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:04:52 INFO - 1500721492553 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:04:52 INFO - 1500721492555 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:04:52 INFO - 1500721492555 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:04:52 INFO - 1500721492576 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:04:52 INFO - 1500721492578 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:04:52 INFO - 1500721492582 Marionette TRACE 370 <- [1,2,null,{}]
04:04:52 INFO - 1500721492591 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
04:04:52 INFO - 1500721492593 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a2dcb766-af8a-4b30-a111-c40be5628262","ELEMENT":"a2dcb766-af8a-4b30-a111-c40be5628262"}}]
04:04:52 INFO - 1500721492594 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a2dcb766-af8a-4b30-a111-c40be5628262"}]
04:04:52 INFO - 1500721492838 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:52 INFO - 1500721492838 Marionette TRACE 370 <- [1,4,null,{}]
04:04:52 INFO - 1500721492840 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a2dcb766-af8a-4b30-a111-c40be5628262","name":"selected"}]
04:04:52 INFO - 1500721492844 Marionette TRACE 370 <- [1,5,null,{"value":true}]
04:04:52 INFO - 1500721492846 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:04:52 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500721492848 Marionette TRACE 370 <- [1,6,null,{"value":null}]
04:04:52 INFO - 1500721492849 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
04:04:52 INFO - 1500721492851 Marionette TRACE 370 <- [1,7,null,{}]
04:04:52 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 363ms
04:04:52 INFO - TEST-START | test_click.py TestClick.test_css_transforms
04:04:52 INFO - 1500721492853 Marionette DEBUG Closed connection 370
04:04:52 INFO - 1500721492855 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49913
04:04:52 INFO - 1500721492855 Marionette DEBUG Closed connection 371
04:04:52 INFO - 1500721492856 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49914
04:04:52 INFO - 1500721492857 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:52 INFO - 1500721492858 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492860 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492863 Marionette TRACE 372 <- [1,1,null,{"sessionId":"3349b716-f4cb-49f4-8969-10d31cb47a89","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492865 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
04:04:52 INFO - 1500721492866 Marionette TRACE 372 <- [1,2,null,{}]
04:04:52 INFO - 1500721492867 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:52 INFO - 1500721492867 Marionette TRACE 372 <- [1,3,null,{}]
04:04:52 INFO - 1500721492869 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:52 INFO - 1500721492869 Marionette TRACE 372 <- [1,4,null,{}]
04:04:52 INFO - 1500721492871 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:04:52 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500721492874 Marionette TRACE 372 <- [1,5,null,{"value":null}]
04:04:52 INFO - 1500721492875 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
04:04:52 INFO - 1500721492876 Marionette TRACE 372 <- [1,6,null,{}]
04:04:52 INFO - 1500721492878 Marionette DEBUG Closed connection 372
04:04:52 INFO - 1500721492879 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49915
04:04:52 INFO - 1500721492880 Marionette DEBUG Closed connection 373
04:04:52 INFO - 1500721492880 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49916
04:04:52 INFO - 1500721492881 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:52 INFO - 1500721492882 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492885 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492888 Marionette TRACE 374 <- [1,1,null,{"sessionId":"86f59b50-c981-4830-a1be-ceaa01f8f4a7","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492890 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
04:04:52 INFO - 1500721492892 Marionette TRACE 374 <- [1,2,null,{}]
04:04:52 INFO - 1500721492894 Marionette DEBUG Closed connection 374
04:04:52 INFO - 1500721492895 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49917
04:04:52 INFO - 1500721492895 Marionette DEBUG Closed connection 375
04:04:52 INFO - 1500721492896 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49918
04:04:52 INFO - 1500721492897 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:52 INFO - 1500721492898 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:52 INFO - 1500721492900 Marionette DEBUG Register listener.js for window 15
04:04:52 INFO - 1500721492903 Marionette TRACE 376 <- [1,1,null,{"sessionId":"2586f001-840e-4501-b3a7-e35987c8cd0c","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:52 INFO - 1500721492906 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:04:52 INFO - 1500721492907 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:04:52 INFO - 1500721492909 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:04:52 INFO - 1500721492910 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:04:52 INFO - 1500721492931 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:04:52 INFO - 1500721492933 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:04:52 INFO - 1500721492942 Marionette TRACE 376 <- [1,2,null,{}]
04:04:52 INFO - 1500721492944 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
04:04:52 INFO - 1500721492946 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"17db0e18-6804-49c0-a9e0-1d39d7c391ab","ELEMENT":"17db0e18-6804-49c0-a9e0-1d39d7c391ab"}}]
04:04:52 INFO - 1500721492947 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"17db0e18-6804-49c0-a9e0-1d39d7c391ab"}]
04:04:53 INFO - 1500721493196 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:53 INFO - 1500721493197 Marionette TRACE 376 <- [1,4,null,{}]
04:04:53 INFO - 1500721493198 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:04:53 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500721493201 Marionette TRACE 376 <- [1,5,null,{"value":null}]
04:04:53 INFO - 1500721493202 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
04:04:53 INFO - 1500721493203 Marionette TRACE 376 <- [1,6,null,{}]
04:04:53 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 351ms
04:04:53 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
04:04:53 INFO - 1500721493206 Marionette DEBUG Closed connection 376
04:04:53 INFO - 1500721493206 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49919
04:04:53 INFO - 1500721493207 Marionette DEBUG Closed connection 377
04:04:53 INFO - 1500721493207 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49920
04:04:53 INFO - 1500721493208 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:53 INFO - 1500721493209 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493212 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493215 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e8738f31-06f6-46ae-8846-b25a2ddfeed1","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493217 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
04:04:53 INFO - 1500721493217 Marionette TRACE 378 <- [1,2,null,{}]
04:04:53 INFO - 1500721493218 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:53 INFO - 1500721493219 Marionette TRACE 378 <- [1,3,null,{}]
04:04:53 INFO - 1500721493220 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:53 INFO - 1500721493220 Marionette TRACE 378 <- [1,4,null,{}]
04:04:53 INFO - 1500721493222 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:04:53 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500721493224 Marionette TRACE 378 <- [1,5,null,{"value":null}]
04:04:53 INFO - 1500721493226 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
04:04:53 INFO - 1500721493227 Marionette TRACE 378 <- [1,6,null,{}]
04:04:53 INFO - 1500721493229 Marionette DEBUG Closed connection 378
04:04:53 INFO - 1500721493230 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49921
04:04:53 INFO - 1500721493231 Marionette DEBUG Closed connection 379
04:04:53 INFO - 1500721493231 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49922
04:04:53 INFO - 1500721493232 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:53 INFO - 1500721493233 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493235 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493238 Marionette TRACE 380 <- [1,1,null,{"sessionId":"c7128552-cdb3-444b-a523-716ed5a2285e","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493240 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
04:04:53 INFO - 1500721493241 Marionette TRACE 380 <- [1,2,null,{}]
04:04:53 INFO - 1500721493244 Marionette DEBUG Closed connection 380
04:04:53 INFO - 1500721493244 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49923
04:04:53 INFO - 1500721493245 Marionette DEBUG Closed connection 381
04:04:53 INFO - 1500721493246 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49924
04:04:53 INFO - 1500721493247 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:53 INFO - 1500721493248 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493250 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493253 Marionette TRACE 382 <- [1,1,null,{"sessionId":"dd150fb0-8626-420b-bb5a-28a0e4d0dab6","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493256 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:04:53 INFO - 1500721493257 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:04:53 INFO - 1500721493260 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:04:53 INFO - 1500721493260 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:04:53 INFO - 1500721493280 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:04:53 INFO - 1500721493283 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:04:53 INFO - 1500721493292 Marionette TRACE 382 <- [1,2,null,{}]
04:04:53 INFO - 1500721493293 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
04:04:53 INFO - 1500721493296 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e61a3ea4-cd0f-4fc6-b612-bcc944f86061","ELEMENT":"e61a3ea4-cd0f-4fc6-b612-bcc944f86061"}}]
04:04:53 INFO - 1500721493297 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e61a3ea4-cd0f-4fc6-b612-bcc944f86061"}]
04:04:53 INFO - 1500721493542 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:53 INFO - 1500721493543 Marionette TRACE 382 <- [1,4,null,{}]
04:04:53 INFO - 1500721493545 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:04:53 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500721493548 Marionette TRACE 382 <- [1,5,null,{"value":null}]
04:04:53 INFO - 1500721493549 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
04:04:53 INFO - 1500721493550 Marionette TRACE 382 <- [1,6,null,{}]
04:04:53 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 346ms
04:04:53 INFO - TEST-START | test_click.py TestClick.test_input_file
04:04:53 INFO - 1500721493552 Marionette DEBUG Closed connection 382
04:04:53 INFO - 1500721493553 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49925
04:04:53 INFO - 1500721493554 Marionette DEBUG Closed connection 383
04:04:53 INFO - 1500721493554 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49926
04:04:53 INFO - 1500721493556 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:53 INFO - 1500721493556 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493559 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493562 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f6a546fb-f142-4a72-98f0-fe150dca657f","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493564 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
04:04:53 INFO - 1500721493564 Marionette TRACE 384 <- [1,2,null,{}]
04:04:53 INFO - 1500721493565 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:53 INFO - 1500721493565 Marionette TRACE 384 <- [1,3,null,{}]
04:04:53 INFO - 1500721493566 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:53 INFO - 1500721493567 Marionette TRACE 384 <- [1,4,null,{}]
04:04:53 INFO - 1500721493568 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:04:53 INFO - TEST-START: test_click.py TestClick.test_input_file1500721493571 Marionette TRACE 384 <- [1,5,null,{"value":null}]
04:04:53 INFO - 1500721493572 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
04:04:53 INFO - 1500721493573 Marionette TRACE 384 <- [1,6,null,{}]
04:04:53 INFO - 1500721493576 Marionette DEBUG Closed connection 384
04:04:53 INFO - 1500721493576 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49927
04:04:53 INFO - 1500721493577 Marionette DEBUG Closed connection 385
04:04:53 INFO - 1500721493578 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49928
04:04:53 INFO - 1500721493579 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:53 INFO - 1500721493580 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493583 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493586 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e6369c31-39fc-4764-99db-0ee4a4ddf581","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493588 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
04:04:53 INFO - 1500721493590 Marionette TRACE 386 <- [1,2,null,{}]
04:04:53 INFO - 1500721493592 Marionette DEBUG Closed connection 386
04:04:53 INFO - 1500721493593 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49929
04:04:53 INFO - 1500721493593 Marionette DEBUG Closed connection 387
04:04:53 INFO - 1500721493594 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49930
04:04:53 INFO - 1500721493595 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:53 INFO - 1500721493596 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493605 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493609 Marionette TRACE 388 <- [1,1,null,{"sessionId":"b7a90d74-3c7c-427c-96bd-9a5943d18e8d","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493611 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
04:04:53 INFO - 1500721493613 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:04:53 INFO - 1500721493615 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:04:53 INFO - 1500721493616 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:04:53 INFO - 1500721493628 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:53 INFO - 1500721493634 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:53 INFO - 1500721493643 Marionette TRACE 388 <- [1,2,null,{}]
04:04:53 INFO - 1500721493646 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
04:04:53 INFO - 1500721493648 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f001666f-bc1d-4b4a-bf65-e14fd43067af","ELEMENT":"f001666f-bc1d-4b4a-bf65-e14fd43067af"}}]
04:04:53 INFO - 1500721493663 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"f001666f-bc1d-4b4a-bf65-e14fd43067af"}]
04:04:53 INFO - 1500721493699 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]
04:04:53 INFO - 1500721493703 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:04:53 INFO - TEST-END: test_click.py TestClick.test_input_file1500721493707 Marionette TRACE 388 <- [1,5,null,{"value":null}]
04:04:53 INFO - 1500721493709 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
04:04:53 INFO - 1500721493712 Marionette TRACE 388 <- [1,6,null,{}]
04:04:53 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 162ms
04:04:53 INFO - TEST-START | test_click.py TestClick.test_obscured_element
04:04:53 INFO - 1500721493715 Marionette DEBUG Closed connection 388
04:04:53 INFO - 1500721493716 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49931
04:04:53 INFO - 1500721493717 Marionette DEBUG Closed connection 389
04:04:53 INFO - 1500721493718 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49932
04:04:53 INFO - 1500721493719 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:53 INFO - 1500721493723 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493726 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493733 Marionette TRACE 390 <- [1,1,null,{"sessionId":"05ce2150-b485-4e52-a219-5836a2e7550b","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493737 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
04:04:53 INFO - 1500721493738 Marionette TRACE 390 <- [1,2,null,{}]
04:04:53 INFO - 1500721493739 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:53 INFO - 1500721493740 Marionette TRACE 390 <- [1,3,null,{}]
04:04:53 INFO - 1500721493741 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:53 INFO - 1500721493742 Marionette TRACE 390 <- [1,4,null,{}]
04:04:53 INFO - 1500721493743 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:04:53 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500721493747 Marionette TRACE 390 <- [1,5,null,{"value":null}]
04:04:53 INFO - 1500721493748 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
04:04:53 INFO - 1500721493750 Marionette TRACE 390 <- [1,6,null,{}]
04:04:53 INFO - 1500721493754 Marionette DEBUG Closed connection 390
04:04:53 INFO - 1500721493755 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49933
04:04:53 INFO - 1500721493756 Marionette DEBUG Closed connection 391
04:04:53 INFO - 1500721493756 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49934
04:04:53 INFO - 1500721493758 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:53 INFO - 1500721493759 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493762 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493766 Marionette TRACE 392 <- [1,1,null,{"sessionId":"abeeda79-86bd-4e1b-a91f-92a9a99c13ed","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493771 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
04:04:53 INFO - 1500721493774 Marionette TRACE 392 <- [1,2,null,{}]
04:04:53 INFO - 1500721493776 Marionette DEBUG Closed connection 392
04:04:53 INFO - 1500721493777 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49935
04:04:53 INFO - 1500721493778 Marionette DEBUG Closed connection 393
04:04:53 INFO - 1500721493778 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49936
04:04:53 INFO - 1500721493780 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:53 INFO - 1500721493781 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:53 INFO - 1500721493784 Marionette DEBUG Register listener.js for window 15
04:04:53 INFO - 1500721493788 Marionette TRACE 394 <- [1,1,null,{"sessionId":"7ac3aaa1-89a4-43a0-a490-36b9b8bbea16","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:53 INFO - 1500721493791 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:04:53 INFO - 1500721493793 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:53 INFO - 1500721493796 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:53 INFO - 1500721493797 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
04:04:53 INFO - 1500721493835 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:04:53 INFO - 1500721493837 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:04:53 INFO - 1500721493842 Marionette TRACE 394 <- [1,2,null,{}]
04:04:53 INFO - 1500721493843 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
04:04:53 INFO - 1500721493847 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4c1b4dc-970d-4c51-ba1a-9bf5cfbe8613","ELEMENT":"c4c1b4dc-970d-4c51-ba1a-9bf5cfbe8613"}}]
04:04:53 INFO - 1500721493854 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
04:04:53 INFO - 1500721493857 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"979ce9fc-e661-4022-9029-18f57ac8d5cf","ELEMENT":"979ce9fc-e661-4022-9029-18f57ac8d5cf"}}]
04:04:53 INFO - 1500721493859 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"c4c1b4dc-970d-4c51-ba1a-9bf5cfbe8613"}]
04:04:54 INFO - 1500721494104 Marionette DEBUG Canceled page load listener because no navigation has been detected
04:04:54 INFO - 1500721494105 Marionette TRACE 394 <- [1,5,null,{}]
04:04:54 INFO - 1500721494106 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"979ce9fc-e661-4022-9029-18f57ac8d5cf"}]
04:04:54 INFO - 1500721494127 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]
04:04:54 INFO - 1500721494130 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
04:04:54 INFO - 1500721494133 Marionette TRACE 394 <- [1,7,null,{"value":false}]
04:04:54 INFO - 1500721494135 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:04:54 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500721494138 Marionette TRACE 394 <- [1,8,null,{"value":null}]
04:04:54 INFO - 1500721494139 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
04:04:54 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 429ms
04:04:54 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
04:04:54 INFO - 1500721494141 Marionette TRACE 394 <- [1,9,null,{}]
04:04:54 INFO - 1500721494144 Marionette DEBUG Closed connection 394
04:04:54 INFO - 1500721494144 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49937
04:04:54 INFO - 1500721494145 Marionette DEBUG Closed connection 395
04:04:54 INFO - 1500721494146 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49938
04:04:54 INFO - 1500721494147 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:54 INFO - 1500721494149 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:54 INFO - 1500721494152 Marionette DEBUG Register listener.js for window 15
04:04:54 INFO - 1500721494156 Marionette TRACE 396 <- [1,1,null,{"sessionId":"e47cca72-7d6e-4569-bbde-1e47b7cf4244","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:54 INFO - 1500721494236 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
04:04:54 INFO - 1500721494237 Marionette TRACE 396 <- [1,2,null,{}]
04:04:54 INFO - 1500721494239 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
04:04:54 INFO - 1500721494241 Marionette TRACE 396 <- [1,3,null,{}]
04:04:54 INFO - 1500721494242 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
04:04:54 INFO - 1500721494244 Marionette TRACE 396 <- [1,4,null,{}]
04:04:54 INFO - 1500721494246 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:04:54 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500721494250 Marionette TRACE 396 <- [1,5,null,{"value":null}]
04:04:54 INFO - 1500721494251 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
04:04:54 INFO - 1500721494254 Marionette TRACE 396 <- [1,6,null,{}]
04:04:54 INFO - 1500721494265 Marionette DEBUG Closed connection 396
04:04:54 INFO - 1500721494266 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49939
04:04:54 INFO - 1500721494268 Marionette DEBUG Closed connection 397
04:04:54 INFO - 1500721494268 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49940
04:04:54 INFO - 1500721494270 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
04:04:54 INFO - 1500721494272 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:54 INFO - 1500721494275 Marionette DEBUG Register listener.js for window 15
04:04:54 INFO - 1500721494281 Marionette TRACE 398 <- [1,1,null,{"sessionId":"9bd1ff85-f8bb-492d-854f-82f590c4003c","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:54 INFO - 1500721494285 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
04:04:54 INFO - 1500721494287 Marionette TRACE 398 <- [1,2,null,{}]
04:04:54 INFO - 1500721494290 Marionette DEBUG Closed connection 398
04:04:54 INFO - 1500721494291 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49941
04:04:54 INFO - 1500721494292 Marionette DEBUG Closed connection 399
04:04:54 INFO - 1500721494293 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49942
04:04:54 INFO - 1500721494295 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
04:04:54 INFO - 1500721494296 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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}
04:04:54 INFO - 1500721494299 Marionette DEBUG Register listener.js for window 15
04:04:54 INFO - 1500721494303 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d371bd75-61e3-4413-89b1-ea9b933c2ff0","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":3732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-320.001\\AppData\\Local\\Temp\\tmprtsaxr.mozrunner","moz:accessibilityChecks":false}}]
04:04:54 INFO - 1500721494307 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:04:54 INFO - 1500721494309 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:04:54 INFO - 1500721494312 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:04:54 INFO - 1500721494313 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:04:54 INFO - 1500721494338 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:04:54 INFO - 1500721494340 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:04:54 INFO - 1500721494345 Marionette TRACE 400 <- [1,2,null,{}]
04:04:54 INFO - 1500721494347 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
04:04:54 INFO - 1500721494356 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f91deaa9-fe69-463e-a310-0eb37bcdda02","ELEMENT":"f91deaa9-fe69-463e-a310-0eb37bcdda02"}}]
04:04:54 INFO - 1500721494359 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f91deaa9-fe69-463e-a310-0eb37bcdda02"}]
04:04:54 INFO - 1500721494398 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
04:04:54 INFO - 1500721494399 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f91deaa9-fe69-463e-a310-0eb37bcdda02"}]
04:04:54 INFO - 1500721494420 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element