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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
01:56:12 INFO - 1499676972940 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}]
01:56:12 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499676972942 Marionette TRACE 352 <- [1,5,null,{"value":null}]
01:56:12 INFO - 1499676972944 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
01:56:12 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
01:56:12 INFO - 1499676972945 Marionette TRACE 352 <- [1,6,null,{}]
01:56:12 INFO - 1499676972947 Marionette DEBUG Closed connection 352
01:56:12 INFO - 1499676972949 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49752
01:56:12 INFO - 1499676972950 Marionette DEBUG Closed connection 353
01:56:12 INFO - 1499676972951 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49753
01:56:12 INFO - 1499676972952 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:12 INFO - 1499676972952 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:12 INFO - 1499676972955 Marionette DEBUG Register listener.js for window 12
01:56:12 INFO - 1499676972959 Marionette TRACE 354 <- [1,1,null,{"sessionId":"a3532820-3a9a-4a83-9e67-7cc42fee9822","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:12 INFO - 1499676972962 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
01:56:12 INFO - 1499676972962 Marionette TRACE 354 <- [1,2,null,{}]
01:56:12 INFO - 1499676972963 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:56:12 INFO - 1499676972964 Marionette TRACE 354 <- [1,3,null,{}]
01:56:12 INFO - 1499676972966 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
01:56:12 INFO - 1499676972966 Marionette TRACE 354 <- [1,4,null,{}]
01:56:12 INFO - 1499676972968 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}]
01:56:12 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499676972970 Marionette TRACE 354 <- [1,5,null,{"value":null}]
01:56:12 INFO - 1499676972971 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
01:56:12 INFO - 1499676972972 Marionette TRACE 354 <- [1,6,null,{}]
01:56:12 INFO - 1499676972975 Marionette DEBUG Closed connection 354
01:56:12 INFO - 1499676972975 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49754
01:56:12 INFO - 1499676972976 Marionette DEBUG Closed connection 355
01:56:12 INFO - 1499676972977 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49755
01:56:12 INFO - 1499676972978 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:12 INFO - 1499676972978 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:12 INFO - 1499676972981 Marionette DEBUG Register listener.js for window 12
01:56:12 INFO - 1499676972984 Marionette TRACE 356 <- [1,1,null,{"sessionId":"a020055e-79fa-4ec6-86b8-7286bd2b4201","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:12 INFO - 1499676972986 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
01:56:12 INFO - 1499676972987 Marionette TRACE 356 <- [1,2,null,{}]
01:56:12 INFO - 1499676972989 Marionette DEBUG Closed connection 356
01:56:12 INFO - 1499676972990 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49756
01:56:12 INFO - 1499676972991 Marionette DEBUG Closed connection 357
01:56:12 INFO - 1499676972991 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49757
01:56:12 INFO - 1499676972992 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:56:12 INFO - 1499676972993 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:12 INFO - 1499676972996 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676972999 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0a9b433f-79a2-4c46-8156-a889d644f369","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973001 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
01:56:13 INFO - 1499676973002 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
01:56:13 INFO - 1499676973008 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
01:56:13 INFO - 1499676973008 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
01:56:13 INFO - 1499676973032 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
01:56:13 INFO - 1499676973034 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
01:56:13 INFO - 1499676973044 Marionette TRACE 358 <- [1,2,null,{}]
01:56:13 INFO - 1499676973045 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
01:56:13 INFO - 1499676973047 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dfef6170-3188-4ce3-bcd5-bacdca6b6981","ELEMENT":"dfef6170-3188-4ce3-bcd5-bacdca6b6981"}}]
01:56:13 INFO - 1499676973048 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"dfef6170-3188-4ce3-bcd5-bacdca6b6981"}]
01:56:13 INFO - 1499676973097 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
01:56:13 INFO - 1499676973106 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
01:56:13 INFO - 1499676973107 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
01:56:13 INFO - 1499676973128 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
01:56:13 INFO - 1499676973129 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
01:56:13 INFO - 1499676973131 Marionette TRACE 358 <- [1,4,null,{}]
01:56:13 INFO - 1499676973138 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
01:56:13 INFO - 1499676973142 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a5b43f8-54b3-42db-b8d4-110c39952ac9","ELEMENT":"8a5b43f8-54b3-42db-b8d4-110c39952ac9"}}]
01:56:13 INFO - 1499676973143 Marionette TRACE 358 -> [0,6,"getTitle",{}]
01:56:13 INFO - 1499676973143 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
01:56:13 INFO - 1499676973145 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}]
01:56:13 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499676973147 Marionette TRACE 358 <- [1,7,null,{"value":null}]
01:56:13 INFO - 1499676973148 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
01:56:13 INFO - 1499676973149 Marionette TRACE 358 <- [1,8,null,{}]
01:56:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 204ms
01:56:13 INFO - TEST-START | test_click.py TestClick.test_container_element
01:56:13 INFO - 1499676973152 Marionette DEBUG Closed connection 358
01:56:13 INFO - 1499676973153 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49760
01:56:13 INFO - 1499676973153 Marionette DEBUG Closed connection 359
01:56:13 INFO - 1499676973154 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49761
01:56:13 INFO - 1499676973156 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:13 INFO - 1499676973156 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973159 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973162 Marionette TRACE 360 <- [1,1,null,{"sessionId":"cf4a667b-d5bd-42e6-9a35-72ca5e39dfb9","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973164 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
01:56:13 INFO - 1499676973164 Marionette TRACE 360 <- [1,2,null,{}]
01:56:13 INFO - 1499676973165 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:56:13 INFO - 1499676973166 Marionette TRACE 360 <- [1,3,null,{}]
01:56:13 INFO - 1499676973167 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
01:56:13 INFO - 1499676973167 Marionette TRACE 360 <- [1,4,null,{}]
01:56:13 INFO - 1499676973169 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}]
01:56:13 INFO - TEST-START: test_click.py TestClick.test_container_element1499676973171 Marionette TRACE 360 <- [1,5,null,{"value":null}]
01:56:13 INFO - 1499676973172 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
01:56:13 INFO - 1499676973173 Marionette TRACE 360 <- [1,6,null,{}]
01:56:13 INFO - 1499676973175 Marionette DEBUG Closed connection 360
01:56:13 INFO - 1499676973176 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49762
01:56:13 INFO - 1499676973177 Marionette DEBUG Closed connection 361
01:56:13 INFO - 1499676973177 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49763
01:56:13 INFO - 1499676973178 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:13 INFO - 1499676973179 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973181 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973184 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f49ef5d8-7feb-43fe-a2d8-1f1a45620829","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973186 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
01:56:13 INFO - 1499676973187 Marionette TRACE 362 <- [1,2,null,{}]
01:56:13 INFO - 1499676973189 Marionette DEBUG Closed connection 362
01:56:13 INFO - 1499676973190 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49764
01:56:13 INFO - 1499676973191 Marionette DEBUG Closed connection 363
01:56:13 INFO - 1499676973191 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49765
01:56:13 INFO - 1499676973192 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:56:13 INFO - 1499676973193 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973196 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973198 Marionette TRACE 364 <- [1,1,null,{"sessionId":"392fefc9-48bb-4d2d-b45e-093b76d33e20","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973201 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"}]
01:56:13 INFO - 1499676973202 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
01:56:13 INFO - 1499676973204 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
01:56:13 INFO - 1499676973204 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
01:56:13 INFO - 1499676973229 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"
01:56:13 INFO - 1499676973230 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"
01:56:13 INFO - 1499676973235 Marionette TRACE 364 <- [1,2,null,{}]
01:56:13 INFO - 1499676973241 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:56:13 INFO - 1499676973244 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"25f9d557-adcb-4351-b63f-c5f0a94c9251","ELEMENT":"25f9d557-adcb-4351-b63f-c5f0a94c9251"}}]
01:56:13 INFO - 1499676973245 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"25f9d557-adcb-4351-b63f-c5f0a94c9251"}]
01:56:13 INFO - 1499676973488 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:56:13 INFO - 1499676973488 Marionette TRACE 364 <- [1,4,null,{}]
01:56:13 INFO - 1499676973489 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"25f9d557-adcb-4351-b63f-c5f0a94c9251","name":"selected"}]
01:56:13 INFO - 1499676973494 Marionette TRACE 364 <- [1,5,null,{"value":true}]
01:56:13 INFO - 1499676973495 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}]
01:56:13 INFO - TEST-END: test_click.py TestClick.test_container_element1499676973498 Marionette TRACE 364 <- [1,6,null,{"value":null}]
01:56:13 INFO - 1499676973499 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
01:56:13 INFO - 1499676973500 Marionette TRACE 364 <- [1,7,null,{}]
01:56:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 351ms
01:56:13 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
01:56:13 INFO - 1499676973502 Marionette DEBUG Closed connection 364
01:56:13 INFO - 1499676973503 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49766
01:56:13 INFO - 1499676973504 Marionette DEBUG Closed connection 365
01:56:13 INFO - 1499676973505 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49767
01:56:13 INFO - 1499676973506 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:13 INFO - 1499676973507 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973509 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973512 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3df179b2-c7af-479e-a567-684b20e7a84d","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973514 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
01:56:13 INFO - 1499676973515 Marionette TRACE 366 <- [1,2,null,{}]
01:56:13 INFO - 1499676973517 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:56:13 INFO - 1499676973517 Marionette TRACE 366 <- [1,3,null,{}]
01:56:13 INFO - 1499676973518 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
01:56:13 INFO - 1499676973520 Marionette TRACE 366 <- [1,4,null,{}]
01:56:13 INFO - 1499676973521 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}]
01:56:13 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499676973524 Marionette TRACE 366 <- [1,5,null,{"value":null}]
01:56:13 INFO - 1499676973525 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
01:56:13 INFO - 1499676973526 Marionette TRACE 366 <- [1,6,null,{}]
01:56:13 INFO - 1499676973528 Marionette DEBUG Closed connection 366
01:56:13 INFO - 1499676973529 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49768
01:56:13 INFO - 1499676973530 Marionette DEBUG Closed connection 367
01:56:13 INFO - 1499676973530 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49769
01:56:13 INFO - 1499676973531 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:13 INFO - 1499676973532 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973534 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973538 Marionette TRACE 368 <- [1,1,null,{"sessionId":"696a86f4-96b0-4fdd-9424-49ff3d1ead32","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973540 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
01:56:13 INFO - 1499676973541 Marionette TRACE 368 <- [1,2,null,{}]
01:56:13 INFO - 1499676973543 Marionette DEBUG Closed connection 368
01:56:13 INFO - 1499676973544 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49770
01:56:13 INFO - 1499676973544 Marionette DEBUG Closed connection 369
01:56:13 INFO - 1499676973545 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49771
01:56:13 INFO - 1499676973546 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:56:13 INFO - 1499676973547 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973556 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973559 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ff99ee19-9185-4843-b048-3049db69c509","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973561 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"}]
01:56:13 INFO - 1499676973563 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"
01:56:13 INFO - 1499676973565 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"
01:56:13 INFO - 1499676973565 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"
01:56:13 INFO - 1499676973583 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"
01:56:13 INFO - 1499676973590 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"
01:56:13 INFO - 1499676973593 Marionette TRACE 370 <- [1,2,null,{}]
01:56:13 INFO - 1499676973594 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
01:56:13 INFO - 1499676973597 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e56ad42-f0fc-4411-869a-21daf177ed22","ELEMENT":"6e56ad42-f0fc-4411-869a-21daf177ed22"}}]
01:56:13 INFO - 1499676973598 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"6e56ad42-f0fc-4411-869a-21daf177ed22"}]
01:56:13 INFO - 1499676973853 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:56:13 INFO - 1499676973853 Marionette TRACE 370 <- [1,4,null,{}]
01:56:13 INFO - 1499676973855 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"6e56ad42-f0fc-4411-869a-21daf177ed22","name":"selected"}]
01:56:13 INFO - 1499676973859 Marionette TRACE 370 <- [1,5,null,{"value":true}]
01:56:13 INFO - 1499676973862 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}]
01:56:13 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499676973864 Marionette TRACE 370 <- [1,6,null,{"value":null}]
01:56:13 INFO - 1499676973865 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
01:56:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 366ms
01:56:13 INFO - TEST-START | test_click.py TestClick.test_css_transforms
01:56:13 INFO - 1499676973866 Marionette TRACE 370 <- [1,7,null,{}]
01:56:13 INFO - 1499676973869 Marionette DEBUG Closed connection 370
01:56:13 INFO - 1499676973869 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49772
01:56:13 INFO - 1499676973870 Marionette DEBUG Closed connection 371
01:56:13 INFO - 1499676973871 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49773
01:56:13 INFO - 1499676973872 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:13 INFO - 1499676973872 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973875 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973878 Marionette TRACE 372 <- [1,1,null,{"sessionId":"48ae59a8-7e25-4dbd-be12-c0a0210ef6cf","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973880 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
01:56:13 INFO - 1499676973880 Marionette TRACE 372 <- [1,2,null,{}]
01:56:13 INFO - 1499676973881 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:56:13 INFO - 1499676973882 Marionette TRACE 372 <- [1,3,null,{}]
01:56:13 INFO - 1499676973883 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
01:56:13 INFO - 1499676973883 Marionette TRACE 372 <- [1,4,null,{}]
01:56:13 INFO - 1499676973885 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}]
01:56:13 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499676973887 Marionette TRACE 372 <- [1,5,null,{"value":null}]
01:56:13 INFO - 1499676973888 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
01:56:13 INFO - 1499676973889 Marionette TRACE 372 <- [1,6,null,{}]
01:56:13 INFO - 1499676973891 Marionette DEBUG Closed connection 372
01:56:13 INFO - 1499676973892 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49774
01:56:13 INFO - 1499676973892 Marionette DEBUG Closed connection 373
01:56:13 INFO - 1499676973893 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49775
01:56:13 INFO - 1499676973894 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:13 INFO - 1499676973894 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973897 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973900 Marionette TRACE 374 <- [1,1,null,{"sessionId":"32cc033d-f0b2-415f-8d42-0d186174b825","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973902 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
01:56:13 INFO - 1499676973903 Marionette TRACE 374 <- [1,2,null,{}]
01:56:13 INFO - 1499676973905 Marionette DEBUG Closed connection 374
01:56:13 INFO - 1499676973905 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49776
01:56:13 INFO - 1499676973906 Marionette DEBUG Closed connection 375
01:56:13 INFO - 1499676973907 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49777
01:56:13 INFO - 1499676973908 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:56:13 INFO - 1499676973909 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:13 INFO - 1499676973911 Marionette DEBUG Register listener.js for window 12
01:56:13 INFO - 1499676973914 Marionette TRACE 376 <- [1,1,null,{"sessionId":"e45a10eb-52cb-4e97-b758-79c65f60ab91","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:13 INFO - 1499676973916 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"}]
01:56:13 INFO - 1499676973917 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"
01:56:13 INFO - 1499676973919 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"
01:56:13 INFO - 1499676973920 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"
01:56:13 INFO - 1499676973932 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"
01:56:13 INFO - 1499676973936 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"
01:56:13 INFO - 1499676973945 Marionette TRACE 376 <- [1,2,null,{}]
01:56:13 INFO - 1499676973948 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
01:56:13 INFO - 1499676973950 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a284cd7-4218-4994-8aa3-f1dad76aced6","ELEMENT":"8a284cd7-4218-4994-8aa3-f1dad76aced6"}}]
01:56:13 INFO - 1499676973951 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"8a284cd7-4218-4994-8aa3-f1dad76aced6"}]
01:56:14 INFO - 1499676974198 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:56:14 INFO - 1499676974198 Marionette TRACE 376 <- [1,4,null,{}]
01:56:14 INFO - 1499676974200 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}]
01:56:14 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499676974203 Marionette TRACE 376 <- [1,5,null,{"value":null}]
01:56:14 INFO - 1499676974204 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
01:56:14 INFO - 1499676974205 Marionette TRACE 376 <- [1,6,null,{}]
01:56:14 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 338ms
01:56:14 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
01:56:14 INFO - 1499676974207 Marionette DEBUG Closed connection 376
01:56:14 INFO - 1499676974207 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49778
01:56:14 INFO - 1499676974208 Marionette DEBUG Closed connection 377
01:56:14 INFO - 1499676974209 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49779
01:56:14 INFO - 1499676974210 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:14 INFO - 1499676974211 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974213 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974216 Marionette TRACE 378 <- [1,1,null,{"sessionId":"fe037d4a-0821-4f7f-b64f-1b9ff6a7248a","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974218 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
01:56:14 INFO - 1499676974219 Marionette TRACE 378 <- [1,2,null,{}]
01:56:14 INFO - 1499676974220 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:56:14 INFO - 1499676974220 Marionette TRACE 378 <- [1,3,null,{}]
01:56:14 INFO - 1499676974221 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
01:56:14 INFO - 1499676974221 Marionette TRACE 378 <- [1,4,null,{}]
01:56:14 INFO - 1499676974223 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}]
01:56:14 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499676974225 Marionette TRACE 378 <- [1,5,null,{"value":null}]
01:56:14 INFO - 1499676974226 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
01:56:14 INFO - 1499676974227 Marionette TRACE 378 <- [1,6,null,{}]
01:56:14 INFO - 1499676974229 Marionette DEBUG Closed connection 378
01:56:14 INFO - 1499676974230 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49780
01:56:14 INFO - 1499676974231 Marionette DEBUG Closed connection 379
01:56:14 INFO - 1499676974232 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49781
01:56:14 INFO - 1499676974232 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:14 INFO - 1499676974233 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974236 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974239 Marionette TRACE 380 <- [1,1,null,{"sessionId":"dbed35de-a253-4dd4-9500-b7bfaa39142b","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974241 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
01:56:14 INFO - 1499676974243 Marionette TRACE 380 <- [1,2,null,{}]
01:56:14 INFO - 1499676974245 Marionette DEBUG Closed connection 380
01:56:14 INFO - 1499676974245 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49782
01:56:14 INFO - 1499676974246 Marionette DEBUG Closed connection 381
01:56:14 INFO - 1499676974247 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49783
01:56:14 INFO - 1499676974248 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:56:14 INFO - 1499676974249 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974252 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974255 Marionette TRACE 382 <- [1,1,null,{"sessionId":"66af736a-7c88-43ea-bd5e-08ec0668a02d","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974257 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"}]
01:56:14 INFO - 1499676974259 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"
01:56:14 INFO - 1499676974261 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"
01:56:14 INFO - 1499676974261 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"
01:56:14 INFO - 1499676974280 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"
01:56:14 INFO - 1499676974286 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"
01:56:14 INFO - 1499676974297 Marionette TRACE 382 <- [1,2,null,{}]
01:56:14 INFO - 1499676974298 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
01:56:14 INFO - 1499676974302 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7222719b-8976-4e0b-b818-0435ece4e5af","ELEMENT":"7222719b-8976-4e0b-b818-0435ece4e5af"}}]
01:56:14 INFO - 1499676974304 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"7222719b-8976-4e0b-b818-0435ece4e5af"}]
01:56:14 INFO - 1499676974556 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:56:14 INFO - 1499676974557 Marionette TRACE 382 <- [1,4,null,{}]
01:56:14 INFO - 1499676974559 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}]
01:56:14 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499676974561 Marionette TRACE 382 <- [1,5,null,{"value":null}]
01:56:14 INFO - 1499676974562 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
01:56:14 INFO - 1499676974564 Marionette TRACE 382 <- [1,6,null,{}]
01:56:14 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 358ms
01:56:14 INFO - TEST-START | test_click.py TestClick.test_input_file
01:56:14 INFO - 1499676974566 Marionette DEBUG Closed connection 382
01:56:14 INFO - 1499676974566 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49784
01:56:14 INFO - 1499676974567 Marionette DEBUG Closed connection 383
01:56:14 INFO - 1499676974568 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49785
01:56:14 INFO - 1499676974569 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:14 INFO - 1499676974570 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974572 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974575 Marionette TRACE 384 <- [1,1,null,{"sessionId":"29576f82-f1df-4a60-ac3f-2d08cf698859","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974577 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
01:56:14 INFO - 1499676974578 Marionette TRACE 384 <- [1,2,null,{}]
01:56:14 INFO - 1499676974579 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:56:14 INFO - 1499676974579 Marionette TRACE 384 <- [1,3,null,{}]
01:56:14 INFO - 1499676974580 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
01:56:14 INFO - 1499676974581 Marionette TRACE 384 <- [1,4,null,{}]
01:56:14 INFO - 1499676974583 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}]
01:56:14 INFO - TEST-START: test_click.py TestClick.test_input_file1499676974585 Marionette TRACE 384 <- [1,5,null,{"value":null}]
01:56:14 INFO - 1499676974586 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
01:56:14 INFO - 1499676974587 Marionette TRACE 384 <- [1,6,null,{}]
01:56:14 INFO - 1499676974590 Marionette DEBUG Closed connection 384
01:56:14 INFO - 1499676974590 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49786
01:56:14 INFO - 1499676974591 Marionette DEBUG Closed connection 385
01:56:14 INFO - 1499676974592 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49787
01:56:14 INFO - 1499676974593 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:14 INFO - 1499676974593 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974596 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974599 Marionette TRACE 386 <- [1,1,null,{"sessionId":"7ae2a608-ef63-411c-a224-b02ea36dc237","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974602 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
01:56:14 INFO - 1499676974603 Marionette TRACE 386 <- [1,2,null,{}]
01:56:14 INFO - 1499676974605 Marionette DEBUG Closed connection 386
01:56:14 INFO - 1499676974605 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49788
01:56:14 INFO - 1499676974606 Marionette DEBUG Closed connection 387
01:56:14 INFO - 1499676974607 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49789
01:56:14 INFO - 1499676974608 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:56:14 INFO - 1499676974609 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974611 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974614 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4ae1cd68-4837-44f4-af5b-43ed6d57e7f2","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974623 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
01:56:14 INFO - 1499676974624 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"
01:56:14 INFO - 1499676974627 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"
01:56:14 INFO - 1499676974627 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"
01:56:14 INFO - 1499676974646 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:56:14 INFO - 1499676974650 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:56:14 INFO - 1499676974658 Marionette TRACE 388 <- [1,2,null,{}]
01:56:14 INFO - 1499676974660 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
01:56:14 INFO - 1499676974662 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3b08dee5-d2a0-4c2a-a57a-cbb4f5a52e58","ELEMENT":"3b08dee5-d2a0-4c2a-a57a-cbb4f5a52e58"}}]
01:56:14 INFO - 1499676974663 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3b08dee5-d2a0-4c2a-a57a-cbb4f5a52e58"}]
01:56:14 INFO - 1499676974691 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
01:56:14 INFO - 1499676974693 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}]
01:56:14 INFO - TEST-END: test_click.py TestClick.test_input_file1499676974696 Marionette TRACE 388 <- [1,5,null,{"value":null}]
01:56:14 INFO - 1499676974697 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
01:56:14 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 135ms
01:56:14 INFO - TEST-START | test_click.py TestClick.test_obscured_element
01:56:14 INFO - 1499676974699 Marionette TRACE 388 <- [1,6,null,{}]
01:56:14 INFO - 1499676974701 Marionette DEBUG Closed connection 388
01:56:14 INFO - 1499676974701 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49790
01:56:14 INFO - 1499676974703 Marionette DEBUG Closed connection 389
01:56:14 INFO - 1499676974703 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49791
01:56:14 INFO - 1499676974704 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:14 INFO - 1499676974705 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974707 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974710 Marionette TRACE 390 <- [1,1,null,{"sessionId":"61ab1400-4038-4bc4-9d77-173f6e98bcf9","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974712 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
01:56:14 INFO - 1499676974713 Marionette TRACE 390 <- [1,2,null,{}]
01:56:14 INFO - 1499676974714 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:56:14 INFO - 1499676974714 Marionette TRACE 390 <- [1,3,null,{}]
01:56:14 INFO - 1499676974715 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
01:56:14 INFO - 1499676974715 Marionette TRACE 390 <- [1,4,null,{}]
01:56:14 INFO - 1499676974717 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}]
01:56:14 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499676974719 Marionette TRACE 390 <- [1,5,null,{"value":null}]
01:56:14 INFO - 1499676974720 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
01:56:14 INFO - 1499676974722 Marionette TRACE 390 <- [1,6,null,{}]
01:56:14 INFO - 1499676974724 Marionette DEBUG Closed connection 390
01:56:14 INFO - 1499676974724 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49792
01:56:14 INFO - 1499676974725 Marionette DEBUG Closed connection 391
01:56:14 INFO - 1499676974726 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49793
01:56:14 INFO - 1499676974727 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:14 INFO - 1499676974727 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974730 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974733 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d32e3d10-bfe0-452e-a43f-e832929d78eb","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974735 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
01:56:14 INFO - 1499676974736 Marionette TRACE 392 <- [1,2,null,{}]
01:56:14 INFO - 1499676974738 Marionette DEBUG Closed connection 392
01:56:14 INFO - 1499676974739 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49794
01:56:14 INFO - 1499676974739 Marionette DEBUG Closed connection 393
01:56:14 INFO - 1499676974740 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49795
01:56:14 INFO - 1499676974741 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:56:14 INFO - 1499676974742 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:14 INFO - 1499676974744 Marionette DEBUG Register listener.js for window 12
01:56:14 INFO - 1499676974747 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f8cb8b80-8e14-43bd-9173-8a4b500b407f","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:14 INFO - 1499676974749 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"}]
01:56:14 INFO - 1499676974750 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:56:14 INFO - 1499676974752 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:56:14 INFO - 1499676974753 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
01:56:14 INFO - 1499676974764 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"
01:56:14 INFO - 1499676974768 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"
01:56:14 INFO - 1499676974778 Marionette TRACE 394 <- [1,2,null,{}]
01:56:14 INFO - 1499676974779 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
01:56:14 INFO - 1499676974782 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eebbaf97-1969-46ea-b36f-84e38ea7f9a2","ELEMENT":"eebbaf97-1969-46ea-b36f-84e38ea7f9a2"}}]
01:56:14 INFO - 1499676974784 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
01:56:14 INFO - 1499676974786 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0e35d225-983e-4240-9946-3b794873e396","ELEMENT":"0e35d225-983e-4240-9946-3b794873e396"}}]
01:56:14 INFO - 1499676974787 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"eebbaf97-1969-46ea-b36f-84e38ea7f9a2"}]
01:56:15 INFO - 1499676975099 Marionette DEBUG Canceled page load listener because no navigation has been detected
01:56:15 INFO - 1499676975100 Marionette TRACE 394 <- [1,5,null,{}]
01:56:15 INFO - 1499676975103 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"0e35d225-983e-4240-9946-3b794873e396"}]
01:56:15 INFO - 1499676975124 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
01:56:15 INFO - 1499676975127 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
01:56:15 INFO - 1499676975131 Marionette TRACE 394 <- [1,7,null,{"value":false}]
01:56:15 INFO - 1499676975134 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}]
01:56:15 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499676975138 Marionette TRACE 394 <- [1,8,null,{"value":null}]
01:56:15 INFO - 1499676975140 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
01:56:15 INFO - 1499676975142 Marionette TRACE 394 <- [1,9,null,{}]
01:56:15 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 444ms
01:56:15 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
01:56:15 INFO - 1499676975146 Marionette DEBUG Closed connection 394
01:56:15 INFO - 1499676975147 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49796
01:56:15 INFO - 1499676975149 Marionette DEBUG Closed connection 395
01:56:15 INFO - 1499676975150 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49797
01:56:15 INFO - 1499676975151 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:15 INFO - 1499676975154 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:15 INFO - 1499676975158 Marionette DEBUG Register listener.js for window 12
01:56:15 INFO - 1499676975165 Marionette TRACE 396 <- [1,1,null,{"sessionId":"b99ce7b4-5e6d-4948-8563-342190aa4a29","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:15 INFO - 1499676975169 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
01:56:15 INFO - 1499676975170 Marionette TRACE 396 <- [1,2,null,{}]
01:56:15 INFO - 1499676975171 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
01:56:15 INFO - 1499676975171 Marionette TRACE 396 <- [1,3,null,{}]
01:56:15 INFO - 1499676975172 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
01:56:15 INFO - 1499676975173 Marionette TRACE 396 <- [1,4,null,{}]
01:56:15 INFO - 1499676975175 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}]
01:56:15 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499676975179 Marionette TRACE 396 <- [1,5,null,{"value":null}]
01:56:15 INFO - 1499676975180 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
01:56:15 INFO - 1499676975183 Marionette TRACE 396 <- [1,6,null,{}]
01:56:15 INFO - 1499676975186 Marionette DEBUG Closed connection 396
01:56:15 INFO - 1499676975187 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49798
01:56:15 INFO - 1499676975224 Marionette DEBUG Closed connection 397
01:56:15 INFO - 1499676975225 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49799
01:56:15 INFO - 1499676975232 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
01:56:15 INFO - 1499676975234 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:15 INFO - 1499676975237 Marionette DEBUG Register listener.js for window 12
01:56:15 INFO - 1499676975242 Marionette TRACE 398 <- [1,1,null,{"sessionId":"32684046-64a5-4332-99be-fc5db79779ee","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:15 INFO - 1499676975245 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
01:56:15 INFO - 1499676975247 Marionette TRACE 398 <- [1,2,null,{}]
01:56:15 INFO - 1499676975250 Marionette DEBUG Closed connection 398
01:56:15 INFO - 1499676975251 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49800
01:56:15 INFO - 1499676975259 Marionette DEBUG Closed connection 399
01:56:15 INFO - 1499676975260 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49801
01:56:15 INFO - 1499676975261 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
01:56:15 INFO - 1499676975263 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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}
01:56:15 INFO - 1499676975267 Marionette DEBUG Register listener.js for window 12
01:56:15 INFO - 1499676975273 Marionette TRACE 400 <- [1,1,null,{"sessionId":"6d19aaee-b46c-47e5-a6c1-ba3770469e55","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":3496,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-144.001\\AppData\\Local\\Temp\\tmpbpstzj.mozrunner","moz:accessibilityChecks":false}}]
01:56:15 INFO - 1499676975277 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"}]
01:56:15 INFO - 1499676975280 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"
01:56:15 INFO - 1499676975286 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"
01:56:15 INFO - 1499676975286 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"
01:56:15 INFO - 1499676975314 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"
01:56:15 INFO - 1499676975317 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"
01:56:15 INFO - 1499676975327 Marionette TRACE 400 <- [1,2,null,{}]
01:56:15 INFO - 1499676975328 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
01:56:15 INFO - 1499676975331 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"afaa8691-9a0f-4cca-bfd9-67089cf7de70","ELEMENT":"afaa8691-9a0f-4cca-bfd9-67089cf7de70"}}]
01:56:15 INFO - 1499676975332 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"afaa8691-9a0f-4cca-bfd9-67089cf7de70"}]
01:56:15 INFO - 1499676975361 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
01:56:15 INFO - 1499676975362 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"afaa8691-9a0f-4cca-bfd9-67089cf7de70"}]
01:56:15 INFO - 1499676975386 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element