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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
14:10:16 INFO - 1499289016960 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}]
14:10:16 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499289016963 Marionette TRACE 352 <- [1,5,null,{"value":null}]
14:10:16 INFO - 1499289016965 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
14:10:16 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 157ms
14:10:16 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
14:10:16 INFO - 1499289016966 Marionette TRACE 352 <- [1,6,null,{}]
14:10:16 INFO - 1499289016968 Marionette DEBUG Closed connection 352
14:10:16 INFO - 1499289016977 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49753
14:10:16 INFO - 1499289016978 Marionette DEBUG Closed connection 353
14:10:16 INFO - 1499289016978 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49754
14:10:16 INFO - 1499289016979 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:16 INFO - 1499289016980 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:16 INFO - 1499289016983 Marionette DEBUG Register listener.js for window 12
14:10:16 INFO - 1499289016986 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7a43b632-6f00-4d1b-8ac9-96e5f7a74d5a","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:16 INFO - 1499289016987 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
14:10:16 INFO - 1499289016988 Marionette TRACE 354 <- [1,2,null,{}]
14:10:16 INFO - 1499289016989 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:10:16 INFO - 1499289016989 Marionette TRACE 354 <- [1,3,null,{}]
14:10:16 INFO - 1499289016990 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
14:10:16 INFO - 1499289016990 Marionette TRACE 354 <- [1,4,null,{}]
14:10:16 INFO - 1499289016992 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}]
14:10:16 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499289016995 Marionette TRACE 354 <- [1,5,null,{"value":null}]
14:10:16 INFO - 1499289016996 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
14:10:16 INFO - 1499289016997 Marionette TRACE 354 <- [1,6,null,{}]
14:10:17 INFO - 1499289016999 Marionette DEBUG Closed connection 354
14:10:17 INFO - 1499289017000 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49755
14:10:17 INFO - 1499289017001 Marionette DEBUG Closed connection 355
14:10:17 INFO - 1499289017001 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49756
14:10:17 INFO - 1499289017002 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:17 INFO - 1499289017003 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017006 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017009 Marionette TRACE 356 <- [1,1,null,{"sessionId":"81fe30e5-cee7-403e-9707-3349985d54ad","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017010 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
14:10:17 INFO - 1499289017011 Marionette TRACE 356 <- [1,2,null,{}]
14:10:17 INFO - 1499289017014 Marionette DEBUG Closed connection 356
14:10:17 INFO - 1499289017014 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49757
14:10:17 INFO - 1499289017015 Marionette DEBUG Closed connection 357
14:10:17 INFO - 1499289017016 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49758
14:10:17 INFO - 1499289017017 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:10:17 INFO - 1499289017017 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017020 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017023 Marionette TRACE 358 <- [1,1,null,{"sessionId":"88c4cfbd-eefc-49ae-999b-8b2a3796f595","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017024 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49328/clicks.html"}]
14:10:17 INFO - 1499289017026 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/hidden.html"
14:10:17 INFO - 1499289017031 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/hidden.html"
14:10:17 INFO - 1499289017031 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/hidden.html"
14:10:17 INFO - 1499289017055 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49328/clicks.html"
14:10:17 INFO - 1499289017056 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49328/clicks.html"
14:10:17 INFO - 1499289017065 Marionette TRACE 358 <- [1,2,null,{}]
14:10:17 INFO - 1499289017072 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
14:10:17 INFO - 1499289017074 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ef8d2d43-37b1-4a33-b2fc-adac79fa39fa","ELEMENT":"ef8d2d43-37b1-4a33-b2fc-adac79fa39fa"}}]
14:10:17 INFO - 1499289017076 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"ef8d2d43-37b1-4a33-b2fc-adac79fa39fa"}]
14:10:17 INFO - 1499289017119 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/clicks.html"
14:10:17 INFO - 1499289017130 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/clicks.html"
14:10:17 INFO - 1499289017131 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/clicks.html"
14:10:17 INFO - 1499289017169 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49328/test.html"
14:10:17 INFO - 1499289017170 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49328/test.html"
14:10:17 INFO - 1499289017173 Marionette TRACE 358 <- [1,4,null,{}]
14:10:17 INFO - 1499289017174 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
14:10:17 INFO - 1499289017176 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ec941cf5-033b-4f80-b04a-72ae91952768","ELEMENT":"ec941cf5-033b-4f80-b04a-72ae91952768"}}]
14:10:17 INFO - 1499289017186 Marionette TRACE 358 -> [0,6,"getTitle",{}]
14:10:17 INFO - 1499289017187 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
14:10:17 INFO - 1499289017190 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}]
14:10:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499289017193 Marionette TRACE 358 <- [1,7,null,{"value":null}]
14:10:17 INFO - 1499289017194 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
14:10:17 INFO - 1499289017195 Marionette TRACE 358 <- [1,8,null,{}]
14:10:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 230ms
14:10:17 INFO - TEST-START | test_click.py TestClick.test_container_element
14:10:17 INFO - 1499289017198 Marionette DEBUG Closed connection 358
14:10:17 INFO - 1499289017199 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49761
14:10:17 INFO - 1499289017200 Marionette DEBUG Closed connection 359
14:10:17 INFO - 1499289017200 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49762
14:10:17 INFO - 1499289017202 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:17 INFO - 1499289017202 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017205 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017208 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3534aecc-af93-4129-bf64-42ab8dd485c3","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017209 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
14:10:17 INFO - 1499289017210 Marionette TRACE 360 <- [1,2,null,{}]
14:10:17 INFO - 1499289017212 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:10:17 INFO - 1499289017213 Marionette TRACE 360 <- [1,3,null,{}]
14:10:17 INFO - 1499289017213 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
14:10:17 INFO - 1499289017214 Marionette TRACE 360 <- [1,4,null,{}]
14:10:17 INFO - 1499289017215 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}]
14:10:17 INFO - TEST-START: test_click.py TestClick.test_container_element1499289017218 Marionette TRACE 360 <- [1,5,null,{"value":null}]
14:10:17 INFO - 1499289017219 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
14:10:17 INFO - 1499289017220 Marionette TRACE 360 <- [1,6,null,{}]
14:10:17 INFO - 1499289017223 Marionette DEBUG Closed connection 360
14:10:17 INFO - 1499289017223 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49763
14:10:17 INFO - 1499289017224 Marionette DEBUG Closed connection 361
14:10:17 INFO - 1499289017225 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49764
14:10:17 INFO - 1499289017226 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:17 INFO - 1499289017226 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017229 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017234 Marionette TRACE 362 <- [1,1,null,{"sessionId":"454b2c20-01de-46b0-a799-7dc7c02d06d9","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017235 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
14:10:17 INFO - 1499289017236 Marionette TRACE 362 <- [1,2,null,{}]
14:10:17 INFO - 1499289017239 Marionette DEBUG Closed connection 362
14:10:17 INFO - 1499289017239 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49765
14:10:17 INFO - 1499289017240 Marionette DEBUG Closed connection 363
14:10:17 INFO - 1499289017241 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49766
14:10:17 INFO - 1499289017242 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:10:17 INFO - 1499289017242 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017245 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017250 Marionette TRACE 364 <- [1,1,null,{"sessionId":"e95dbef1-9550-43e2-b299-67707c88828f","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017251 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"}]
14:10:17 INFO - 1499289017252 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49328/test.html"
14:10:17 INFO - 1499289017254 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49328/test.html"
14:10:17 INFO - 1499289017255 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49328/test.html"
14:10:17 INFO - 1499289017282 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"
14:10:17 INFO - 1499289017284 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"
14:10:17 INFO - 1499289017288 Marionette TRACE 364 <- [1,2,null,{}]
14:10:17 INFO - 1499289017289 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:10:17 INFO - 1499289017292 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"89beaa9d-da20-4d41-9252-6790dab7519f","ELEMENT":"89beaa9d-da20-4d41-9252-6790dab7519f"}}]
14:10:17 INFO - 1499289017293 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"89beaa9d-da20-4d41-9252-6790dab7519f"}]
14:10:17 INFO - 1499289017550 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:10:17 INFO - 1499289017551 Marionette TRACE 364 <- [1,4,null,{}]
14:10:17 INFO - 1499289017552 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"89beaa9d-da20-4d41-9252-6790dab7519f","name":"selected"}]
14:10:17 INFO - 1499289017556 Marionette TRACE 364 <- [1,5,null,{"value":true}]
14:10:17 INFO - 1499289017558 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}]
14:10:17 INFO - TEST-END: test_click.py TestClick.test_container_element1499289017560 Marionette TRACE 364 <- [1,6,null,{"value":null}]
14:10:17 INFO - 1499289017561 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
14:10:17 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 365ms
14:10:17 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
14:10:17 INFO - 1499289017563 Marionette TRACE 364 <- [1,7,null,{}]
14:10:17 INFO - 1499289017565 Marionette DEBUG Closed connection 364
14:10:17 INFO - 1499289017565 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49767
14:10:17 INFO - 1499289017566 Marionette DEBUG Closed connection 365
14:10:17 INFO - 1499289017567 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49768
14:10:17 INFO - 1499289017568 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:17 INFO - 1499289017568 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017571 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017574 Marionette TRACE 366 <- [1,1,null,{"sessionId":"6d5a14af-43d4-4ef8-ab61-28c6f71776d8","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017575 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
14:10:17 INFO - 1499289017576 Marionette TRACE 366 <- [1,2,null,{}]
14:10:17 INFO - 1499289017577 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:10:17 INFO - 1499289017577 Marionette TRACE 366 <- [1,3,null,{}]
14:10:17 INFO - 1499289017578 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
14:10:17 INFO - 1499289017579 Marionette TRACE 366 <- [1,4,null,{}]
14:10:17 INFO - 1499289017580 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}]
14:10:17 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499289017583 Marionette TRACE 366 <- [1,5,null,{"value":null}]
14:10:17 INFO - 1499289017584 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
14:10:17 INFO - 1499289017586 Marionette TRACE 366 <- [1,6,null,{}]
14:10:17 INFO - 1499289017588 Marionette DEBUG Closed connection 366
14:10:17 INFO - 1499289017589 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49769
14:10:17 INFO - 1499289017590 Marionette DEBUG Closed connection 367
14:10:17 INFO - 1499289017590 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49770
14:10:17 INFO - 1499289017591 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:17 INFO - 1499289017592 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017594 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017598 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ecf279ff-4044-40a9-83f6-04becb6d647f","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017599 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
14:10:17 INFO - 1499289017600 Marionette TRACE 368 <- [1,2,null,{}]
14:10:17 INFO - 1499289017602 Marionette DEBUG Closed connection 368
14:10:17 INFO - 1499289017603 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49771
14:10:17 INFO - 1499289017604 Marionette DEBUG Closed connection 369
14:10:17 INFO - 1499289017604 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49772
14:10:17 INFO - 1499289017605 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:10:17 INFO - 1499289017606 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017608 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017611 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c8afb3b1-d884-4632-b8dd-2104bf738863","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017613 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"}]
14:10:17 INFO - 1499289017614 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"
14:10:17 INFO - 1499289017616 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"
14:10:17 INFO - 1499289017616 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"
14:10:17 INFO - 1499289017635 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"
14:10:17 INFO - 1499289017637 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"
14:10:17 INFO - 1499289017640 Marionette TRACE 370 <- [1,2,null,{}]
14:10:17 INFO - 1499289017642 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:10:17 INFO - 1499289017652 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dbed4076-8758-419b-a0dc-09a65d84805c","ELEMENT":"dbed4076-8758-419b-a0dc-09a65d84805c"}}]
14:10:17 INFO - 1499289017659 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"dbed4076-8758-419b-a0dc-09a65d84805c"}]
14:10:17 INFO - 1499289017905 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:10:17 INFO - 1499289017905 Marionette TRACE 370 <- [1,4,null,{}]
14:10:17 INFO - 1499289017907 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"dbed4076-8758-419b-a0dc-09a65d84805c","name":"selected"}]
14:10:17 INFO - 1499289017913 Marionette TRACE 370 <- [1,5,null,{"value":true}]
14:10:17 INFO - 1499289017915 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}]
14:10:17 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499289017917 Marionette TRACE 370 <- [1,6,null,{"value":null}]
14:10:17 INFO - 1499289017918 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
14:10:17 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 357ms
14:10:17 INFO - TEST-START | test_click.py TestClick.test_css_transforms
14:10:17 INFO - 1499289017920 Marionette TRACE 370 <- [1,7,null,{}]
14:10:17 INFO - 1499289017922 Marionette DEBUG Closed connection 370
14:10:17 INFO - 1499289017923 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49773
14:10:17 INFO - 1499289017923 Marionette DEBUG Closed connection 371
14:10:17 INFO - 1499289017924 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49774
14:10:17 INFO - 1499289017925 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:17 INFO - 1499289017926 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017929 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017938 Marionette TRACE 372 <- [1,1,null,{"sessionId":"053c40c9-535b-429c-a73b-98b4eec1b7e7","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017940 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
14:10:17 INFO - 1499289017940 Marionette TRACE 372 <- [1,2,null,{}]
14:10:17 INFO - 1499289017941 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:10:17 INFO - 1499289017942 Marionette TRACE 372 <- [1,3,null,{}]
14:10:17 INFO - 1499289017943 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
14:10:17 INFO - 1499289017943 Marionette TRACE 372 <- [1,4,null,{}]
14:10:17 INFO - 1499289017945 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}]
14:10:17 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499289017947 Marionette TRACE 372 <- [1,5,null,{"value":null}]
14:10:17 INFO - 1499289017948 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
14:10:17 INFO - 1499289017950 Marionette TRACE 372 <- [1,6,null,{}]
14:10:17 INFO - 1499289017952 Marionette DEBUG Closed connection 372
14:10:17 INFO - 1499289017953 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49775
14:10:17 INFO - 1499289017953 Marionette DEBUG Closed connection 373
14:10:17 INFO - 1499289017954 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49776
14:10:17 INFO - 1499289017955 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:17 INFO - 1499289017955 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017958 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017961 Marionette TRACE 374 <- [1,1,null,{"sessionId":"b5ef3a6c-9d91-45bc-841e-28bf2128f332","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017963 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
14:10:17 INFO - 1499289017964 Marionette TRACE 374 <- [1,2,null,{}]
14:10:17 INFO - 1499289017966 Marionette DEBUG Closed connection 374
14:10:17 INFO - 1499289017966 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49777
14:10:17 INFO - 1499289017967 Marionette DEBUG Closed connection 375
14:10:17 INFO - 1499289017968 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49778
14:10:17 INFO - 1499289017969 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:10:17 INFO - 1499289017970 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:17 INFO - 1499289017972 Marionette DEBUG Register listener.js for window 12
14:10:17 INFO - 1499289017975 Marionette TRACE 376 <- [1,1,null,{"sessionId":"cf9744af-96b0-4eda-b5a3-9bd80ea344af","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:17 INFO - 1499289017977 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"}]
14:10:17 INFO - 1499289017978 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"
14:10:17 INFO - 1499289017981 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"
14:10:17 INFO - 1499289017981 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"
14:10:18 INFO - 1499289018001 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"
14:10:18 INFO - 1499289018003 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"
14:10:18 INFO - 1499289018007 Marionette TRACE 376 <- [1,2,null,{}]
14:10:18 INFO - 1499289018008 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
14:10:18 INFO - 1499289018015 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf7e6449-23e8-47b4-8528-8327126c368e","ELEMENT":"cf7e6449-23e8-47b4-8528-8327126c368e"}}]
14:10:18 INFO - 1499289018016 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"cf7e6449-23e8-47b4-8528-8327126c368e"}]
14:10:18 INFO - 1499289018257 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:10:18 INFO - 1499289018258 Marionette TRACE 376 <- [1,4,null,{}]
14:10:18 INFO - 1499289018261 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}]
14:10:18 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499289018263 Marionette TRACE 376 <- [1,5,null,{"value":null}]
14:10:18 INFO - 1499289018264 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
14:10:18 INFO - 1499289018266 Marionette TRACE 376 <- [1,6,null,{}]
14:10:18 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 346ms
14:10:18 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
14:10:18 INFO - 1499289018268 Marionette DEBUG Closed connection 376
14:10:18 INFO - 1499289018268 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49779
14:10:18 INFO - 1499289018269 Marionette DEBUG Closed connection 377
14:10:18 INFO - 1499289018270 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49780
14:10:18 INFO - 1499289018271 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:18 INFO - 1499289018272 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018274 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018278 Marionette TRACE 378 <- [1,1,null,{"sessionId":"aab19767-c342-4a1e-a18b-19a1156a6855","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018279 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
14:10:18 INFO - 1499289018279 Marionette TRACE 378 <- [1,2,null,{}]
14:10:18 INFO - 1499289018280 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:10:18 INFO - 1499289018281 Marionette TRACE 378 <- [1,3,null,{}]
14:10:18 INFO - 1499289018282 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
14:10:18 INFO - 1499289018282 Marionette TRACE 378 <- [1,4,null,{}]
14:10:18 INFO - 1499289018283 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}]
14:10:18 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499289018286 Marionette TRACE 378 <- [1,5,null,{"value":null}]
14:10:18 INFO - 1499289018286 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
14:10:18 INFO - 1499289018288 Marionette TRACE 378 <- [1,6,null,{}]
14:10:18 INFO - 1499289018290 Marionette DEBUG Closed connection 378
14:10:18 INFO - 1499289018290 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49781
14:10:18 INFO - 1499289018291 Marionette DEBUG Closed connection 379
14:10:18 INFO - 1499289018291 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49782
14:10:18 INFO - 1499289018292 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:18 INFO - 1499289018293 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018296 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018299 Marionette TRACE 380 <- [1,1,null,{"sessionId":"f47d4722-c8fe-408d-8e8c-db57d6d78d45","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018300 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
14:10:18 INFO - 1499289018301 Marionette TRACE 380 <- [1,2,null,{}]
14:10:18 INFO - 1499289018303 Marionette DEBUG Closed connection 380
14:10:18 INFO - 1499289018303 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49783
14:10:18 INFO - 1499289018304 Marionette DEBUG Closed connection 381
14:10:18 INFO - 1499289018305 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49784
14:10:18 INFO - 1499289018306 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:10:18 INFO - 1499289018307 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018309 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018312 Marionette TRACE 382 <- [1,1,null,{"sessionId":"5b227570-e504-4ee8-ac68-e4685c98516d","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018314 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"}]
14:10:18 INFO - 1499289018315 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"
14:10:18 INFO - 1499289018317 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"
14:10:18 INFO - 1499289018318 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"
14:10:18 INFO - 1499289018339 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"
14:10:18 INFO - 1499289018341 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"
14:10:18 INFO - 1499289018345 Marionette TRACE 382 <- [1,2,null,{}]
14:10:18 INFO - 1499289018352 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
14:10:18 INFO - 1499289018354 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"38b57f22-4dd1-495c-9238-84245ded323a","ELEMENT":"38b57f22-4dd1-495c-9238-84245ded323a"}}]
14:10:18 INFO - 1499289018355 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"38b57f22-4dd1-495c-9238-84245ded323a"}]
14:10:18 INFO - 1499289018604 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:10:18 INFO - 1499289018605 Marionette TRACE 382 <- [1,4,null,{}]
14:10:18 INFO - 1499289018607 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}]
14:10:18 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499289018609 Marionette TRACE 382 <- [1,5,null,{"value":null}]
14:10:18 INFO - 1499289018610 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
14:10:18 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 346ms
14:10:18 INFO - 1499289018612 Marionette TRACE 382 <- [1,6,null,{}]
14:10:18 INFO - TEST-START | test_click.py TestClick.test_input_file
14:10:18 INFO - 1499289018614 Marionette DEBUG Closed connection 382
14:10:18 INFO - 1499289018615 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49785
14:10:18 INFO - 1499289018616 Marionette DEBUG Closed connection 383
14:10:18 INFO - 1499289018616 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49786
14:10:18 INFO - 1499289018617 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:18 INFO - 1499289018618 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018621 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018624 Marionette TRACE 384 <- [1,1,null,{"sessionId":"45061f61-7b49-4cfd-b40a-03572c8859a1","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018625 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
14:10:18 INFO - 1499289018625 Marionette TRACE 384 <- [1,2,null,{}]
14:10:18 INFO - 1499289018626 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:10:18 INFO - 1499289018627 Marionette TRACE 384 <- [1,3,null,{}]
14:10:18 INFO - 1499289018628 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
14:10:18 INFO - 1499289018628 Marionette TRACE 384 <- [1,4,null,{}]
14:10:18 INFO - 1499289018630 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}]
14:10:18 INFO - TEST-START: test_click.py TestClick.test_input_file1499289018632 Marionette TRACE 384 <- [1,5,null,{"value":null}]
14:10:18 INFO - 1499289018634 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
14:10:18 INFO - 1499289018635 Marionette TRACE 384 <- [1,6,null,{}]
14:10:18 INFO - 1499289018637 Marionette DEBUG Closed connection 384
14:10:18 INFO - 1499289018637 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49787
14:10:18 INFO - 1499289018638 Marionette DEBUG Closed connection 385
14:10:18 INFO - 1499289018639 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49788
14:10:18 INFO - 1499289018640 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:18 INFO - 1499289018641 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018643 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018646 Marionette TRACE 386 <- [1,1,null,{"sessionId":"580de332-10ed-4946-aca2-d12440683a38","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018647 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
14:10:18 INFO - 1499289018649 Marionette TRACE 386 <- [1,2,null,{}]
14:10:18 INFO - 1499289018651 Marionette DEBUG Closed connection 386
14:10:18 INFO - 1499289018651 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49789
14:10:18 INFO - 1499289018653 Marionette DEBUG Closed connection 387
14:10:18 INFO - 1499289018653 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49790
14:10:18 INFO - 1499289018654 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:10:18 INFO - 1499289018655 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018657 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018660 Marionette TRACE 388 <- [1,1,null,{"sessionId":"51d63830-344c-4214-8c14-16e4a2624636","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018661 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
14:10:18 INFO - 1499289018663 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"
14:10:18 INFO - 1499289018665 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"
14:10:18 INFO - 1499289018665 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"
14:10:18 INFO - 1499289018676 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:10:18 INFO - 1499289018686 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:10:18 INFO - 1499289018690 Marionette TRACE 388 <- [1,2,null,{}]
14:10:18 INFO - 1499289018691 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
14:10:18 INFO - 1499289018698 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6709fe34-65e9-4b77-a68d-987e6fc27885","ELEMENT":"6709fe34-65e9-4b77-a68d-987e6fc27885"}}]
14:10:18 INFO - 1499289018703 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6709fe34-65e9-4b77-a68d-987e6fc27885"}]
14:10:18 INFO - 1499289018732 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
14:10:18 INFO - 1499289018734 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}]
14:10:18 INFO - TEST-END: test_click.py TestClick.test_input_file1499289018736 Marionette TRACE 388 <- [1,5,null,{"value":null}]
14:10:18 INFO - 1499289018737 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
14:10:18 INFO - 1499289018739 Marionette TRACE 388 <- [1,6,null,{}]
14:10:18 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 127ms
14:10:18 INFO - TEST-START | test_click.py TestClick.test_obscured_element
14:10:18 INFO - 1499289018741 Marionette DEBUG Closed connection 388
14:10:18 INFO - 1499289018742 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49791
14:10:18 INFO - 1499289018743 Marionette DEBUG Closed connection 389
14:10:18 INFO - 1499289018744 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49792
14:10:18 INFO - 1499289018745 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:18 INFO - 1499289018745 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018748 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018752 Marionette TRACE 390 <- [1,1,null,{"sessionId":"e0275028-44bc-49b1-a86a-d895a58f2125","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018753 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
14:10:18 INFO - 1499289018754 Marionette TRACE 390 <- [1,2,null,{}]
14:10:18 INFO - 1499289018754 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:10:18 INFO - 1499289018755 Marionette TRACE 390 <- [1,3,null,{}]
14:10:18 INFO - 1499289018756 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
14:10:18 INFO - 1499289018756 Marionette TRACE 390 <- [1,4,null,{}]
14:10:18 INFO - 1499289018758 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}]
14:10:18 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499289018761 Marionette TRACE 390 <- [1,5,null,{"value":null}]
14:10:18 INFO - 1499289018762 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
14:10:18 INFO - 1499289018763 Marionette TRACE 390 <- [1,6,null,{}]
14:10:18 INFO - 1499289018765 Marionette DEBUG Closed connection 390
14:10:18 INFO - 1499289018766 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49793
14:10:18 INFO - 1499289018766 Marionette DEBUG Closed connection 391
14:10:18 INFO - 1499289018767 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49794
14:10:18 INFO - 1499289018768 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:18 INFO - 1499289018769 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018771 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018774 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0b44c2a8-ee66-4e6e-bd08-7f9e43370f71","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018776 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
14:10:18 INFO - 1499289018777 Marionette TRACE 392 <- [1,2,null,{}]
14:10:18 INFO - 1499289018779 Marionette DEBUG Closed connection 392
14:10:18 INFO - 1499289018779 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49795
14:10:18 INFO - 1499289018780 Marionette DEBUG Closed connection 393
14:10:18 INFO - 1499289018781 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49796
14:10:18 INFO - 1499289018782 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:10:18 INFO - 1499289018782 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:18 INFO - 1499289018785 Marionette DEBUG Register listener.js for window 12
14:10:18 INFO - 1499289018794 Marionette TRACE 394 <- [1,1,null,{"sessionId":"2326fa58-1f03-4427-8ef1-342850c13f73","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:18 INFO - 1499289018796 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"}]
14:10:18 INFO - 1499289018797 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:10:18 INFO - 1499289018800 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:10:18 INFO - 1499289018800 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:10:18 INFO - 1499289018820 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"
14:10:18 INFO - 1499289018822 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"
14:10:18 INFO - 1499289018825 Marionette TRACE 394 <- [1,2,null,{}]
14:10:18 INFO - 1499289018831 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
14:10:18 INFO - 1499289018834 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05197739-6914-4cb1-b8e3-68af1d191984","ELEMENT":"05197739-6914-4cb1-b8e3-68af1d191984"}}]
14:10:18 INFO - 1499289018835 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
14:10:18 INFO - 1499289018836 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3afeae2f-4992-4ac7-bc3a-dedab1c7ea4b","ELEMENT":"3afeae2f-4992-4ac7-bc3a-dedab1c7ea4b"}}]
14:10:18 INFO - 1499289018837 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"05197739-6914-4cb1-b8e3-68af1d191984"}]
14:10:19 INFO - 1499289019138 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:10:19 INFO - 1499289019139 Marionette TRACE 394 <- [1,5,null,{}]
14:10:19 INFO - 1499289019141 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"3afeae2f-4992-4ac7-bc3a-dedab1c7ea4b"}]
14:10:19 INFO - 1499289019164 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
14:10:19 INFO - 1499289019174 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
14:10:19 INFO - 1499289019179 Marionette TRACE 394 <- [1,7,null,{"value":false}]
14:10:19 INFO - 1499289019184 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}]
14:10:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499289019188 Marionette TRACE 394 <- [1,8,null,{"value":null}]
14:10:19 INFO - 1499289019190 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
14:10:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 454ms
14:10:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
14:10:19 INFO - 1499289019192 Marionette TRACE 394 <- [1,9,null,{}]
14:10:19 INFO - 1499289019196 Marionette DEBUG Closed connection 394
14:10:19 INFO - 1499289019197 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49797
14:10:19 INFO - 1499289019199 Marionette DEBUG Closed connection 395
14:10:19 INFO - 1499289019200 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49798
14:10:19 INFO - 1499289019202 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:19 INFO - 1499289019205 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:19 INFO - 1499289019208 Marionette DEBUG Register listener.js for window 12
14:10:19 INFO - 1499289019215 Marionette TRACE 396 <- [1,1,null,{"sessionId":"5a47f8d8-d3a8-4af6-8407-f69a5f1782c5","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:19 INFO - 1499289019217 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
14:10:19 INFO - 1499289019218 Marionette TRACE 396 <- [1,2,null,{}]
14:10:19 INFO - 1499289019220 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:10:19 INFO - 1499289019221 Marionette TRACE 396 <- [1,3,null,{}]
14:10:19 INFO - 1499289019222 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
14:10:19 INFO - 1499289019222 Marionette TRACE 396 <- [1,4,null,{}]
14:10:19 INFO - 1499289019225 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}]
14:10:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499289019229 Marionette TRACE 396 <- [1,5,null,{"value":null}]
14:10:19 INFO - 1499289019230 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
14:10:19 INFO - 1499289019232 Marionette TRACE 396 <- [1,6,null,{}]
14:10:19 INFO - 1499289019235 Marionette DEBUG Closed connection 396
14:10:19 INFO - 1499289019236 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49799
14:10:19 INFO - 1499289019237 Marionette DEBUG Closed connection 397
14:10:19 INFO - 1499289019238 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49800
14:10:19 INFO - 1499289019241 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:10:19 INFO - 1499289019243 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:19 INFO - 1499289019246 Marionette DEBUG Register listener.js for window 12
14:10:19 INFO - 1499289019251 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d32944d4-ecfe-4b96-9922-ea1a54161597","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:19 INFO - 1499289019253 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
14:10:19 INFO - 1499289019255 Marionette TRACE 398 <- [1,2,null,{}]
14:10:19 INFO - 1499289019257 Marionette DEBUG Closed connection 398
14:10:19 INFO - 1499289019258 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49801
14:10:19 INFO - 1499289019259 Marionette DEBUG Closed connection 399
14:10:19 INFO - 1499289019260 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49802
14:10:19 INFO - 1499289019261 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:10:19 INFO - 1499289019262 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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}
14:10:19 INFO - 1499289019265 Marionette DEBUG Register listener.js for window 12
14:10:19 INFO - 1499289019269 Marionette TRACE 400 <- [1,1,null,{"sessionId":"fee64a78-cd13-4186-994b-6e7d47d63751","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":3616,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-194.001\\AppData\\Local\\Temp\\tmpr1bymf.mozrunner","moz:accessibilityChecks":false}}]
14:10:19 INFO - 1499289019271 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"}]
14:10:19 INFO - 1499289019272 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"
14:10:19 INFO - 1499289019277 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"
14:10:19 INFO - 1499289019277 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"
14:10:19 INFO - 1499289019313 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"
14:10:19 INFO - 1499289019317 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"
14:10:19 INFO - 1499289019327 Marionette TRACE 400 <- [1,2,null,{}]
14:10:19 INFO - 1499289019329 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
14:10:19 INFO - 1499289019332 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c5f49127-df6f-4831-8adb-0bf3cc8a805f","ELEMENT":"c5f49127-df6f-4831-8adb-0bf3cc8a805f"}}]
14:10:19 INFO - 1499289019335 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c5f49127-df6f-4831-8adb-0bf3cc8a805f"}]
14:10:19 INFO - 1499289019362 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
14:10:19 INFO - 1499289019363 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"c5f49127-df6f-4831-8adb-0bf3cc8a805f"}]
14:10:19 INFO - 1499289019386 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element