could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
15:39:21 INFO - 1500417561089 Marionette TRACE 353 -> [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}]
15:39:21 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500417561092 Marionette TRACE 353 <- [1,5,null,{"value":null}]
15:39:21 INFO - 1500417561092 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
15:39:21 INFO - 1500417561094 Marionette TRACE 353 <- [1,6,null,{}]
15:39:21 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:39:21 INFO - 1500417561096 Marionette DEBUG Closed connection 353
15:39:21 INFO - 1500417561097 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49769
15:39:21 INFO - 1500417561098 Marionette DEBUG Closed connection 354
15:39:21 INFO - 1500417561098 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49770
15:39:21 INFO - 1500417561099 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:21 INFO - 1500417561100 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561103 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561106 Marionette TRACE 355 <- [1,1,null,{"sessionId":"6f799847-d684-4989-9ee7-896e6d1008e1","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561109 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
15:39:21 INFO - 1500417561109 Marionette TRACE 355 <- [1,2,null,{}]
15:39:21 INFO - 1500417561110 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:39:21 INFO - 1500417561110 Marionette TRACE 355 <- [1,3,null,{}]
15:39:21 INFO - 1500417561111 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
15:39:21 INFO - 1500417561112 Marionette TRACE 355 <- [1,4,null,{}]
15:39:21 INFO - 1500417561113 Marionette TRACE 355 -> [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}]
15:39:21 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500417561116 Marionette TRACE 355 <- [1,5,null,{"value":null}]
15:39:21 INFO - 1500417561117 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
15:39:21 INFO - 1500417561118 Marionette TRACE 355 <- [1,6,null,{}]
15:39:21 INFO - 1500417561121 Marionette DEBUG Closed connection 355
15:39:21 INFO - 1500417561121 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49771
15:39:21 INFO - 1500417561122 Marionette DEBUG Closed connection 356
15:39:21 INFO - 1500417561123 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49772
15:39:21 INFO - 1500417561124 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:21 INFO - 1500417561124 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561127 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561130 Marionette TRACE 357 <- [1,1,null,{"sessionId":"f28c313f-196d-4a92-9d70-dd70aa748297","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561133 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
15:39:21 INFO - 1500417561134 Marionette TRACE 357 <- [1,2,null,{}]
15:39:21 INFO - 1500417561136 Marionette DEBUG Closed connection 357
15:39:21 INFO - 1500417561137 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49773
15:39:21 INFO - 1500417561138 Marionette DEBUG Closed connection 358
15:39:21 INFO - 1500417561138 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49774
15:39:21 INFO - 1500417561139 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:39:21 INFO - 1500417561140 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561143 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561146 Marionette TRACE 359 <- [1,1,null,{"sessionId":"b3cc0cd5-887b-4ed1-8361-94a5580624f9","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561148 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49340/clicks.html"}]
15:39:21 INFO - 1500417561150 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/hidden.html"
15:39:21 INFO - 1500417561154 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/hidden.html"
15:39:21 INFO - 1500417561155 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/hidden.html"
15:39:21 INFO - 1500417561172 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49340/clicks.html"
15:39:21 INFO - 1500417561178 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49340/clicks.html"
15:39:21 INFO - 1500417561188 Marionette TRACE 359 <- [1,2,null,{}]
15:39:21 INFO - 1500417561191 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:39:21 INFO - 1500417561195 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c8687966-13a5-4363-9dd2-9536d06b50b3","ELEMENT":"c8687966-13a5-4363-9dd2-9536d06b50b3"}}]
15:39:21 INFO - 1500417561196 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"c8687966-13a5-4363-9dd2-9536d06b50b3"}]
15:39:21 INFO - 1500417561245 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/clicks.html"
15:39:21 INFO - 1500417561254 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/clicks.html"
15:39:21 INFO - 1500417561254 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/clicks.html"
15:39:21 INFO - 1500417561272 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49340/test.html"
15:39:21 INFO - 1500417561275 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49340/test.html"
15:39:21 INFO - 1500417561284 Marionette TRACE 359 <- [1,4,null,{}]
15:39:21 INFO - 1500417561286 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:39:21 INFO - 1500417561288 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4410b4a6-f001-483d-bbd1-ac42c6cdca39","ELEMENT":"4410b4a6-f001-483d-bbd1-ac42c6cdca39"}}]
15:39:21 INFO - 1500417561289 Marionette TRACE 359 -> [0,6,"getTitle",{}]
15:39:21 INFO - 1500417561290 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
15:39:21 INFO - 1500417561291 Marionette TRACE 359 -> [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}]
15:39:21 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500417561295 Marionette TRACE 359 <- [1,7,null,{"value":null}]
15:39:21 INFO - 1500417561296 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
15:39:21 INFO - 1500417561297 Marionette TRACE 359 <- [1,8,null,{}]
15:39:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 202ms
15:39:21 INFO - TEST-START | test_click.py TestClick.test_container_element
15:39:21 INFO - 1500417561299 Marionette DEBUG Closed connection 359
15:39:21 INFO - 1500417561300 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49777
15:39:21 INFO - 1500417561301 Marionette DEBUG Closed connection 360
15:39:21 INFO - 1500417561301 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49778
15:39:21 INFO - 1500417561302 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:21 INFO - 1500417561303 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561306 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561309 Marionette TRACE 361 <- [1,1,null,{"sessionId":"2723580d-ebe6-47ee-80f5-4a9c8e62d9f3","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561311 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
15:39:21 INFO - 1500417561312 Marionette TRACE 361 <- [1,2,null,{}]
15:39:21 INFO - 1500417561312 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:39:21 INFO - 1500417561313 Marionette TRACE 361 <- [1,3,null,{}]
15:39:21 INFO - 1500417561314 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
15:39:21 INFO - 1500417561314 Marionette TRACE 361 <- [1,4,null,{}]
15:39:21 INFO - 1500417561316 Marionette TRACE 361 -> [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}]
15:39:21 INFO - TEST-START: test_click.py TestClick.test_container_element1500417561318 Marionette TRACE 361 <- [1,5,null,{"value":null}]
15:39:21 INFO - 1500417561319 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
15:39:21 INFO - 1500417561321 Marionette TRACE 361 <- [1,6,null,{}]
15:39:21 INFO - 1500417561323 Marionette DEBUG Closed connection 361
15:39:21 INFO - 1500417561323 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49779
15:39:21 INFO - 1500417561324 Marionette DEBUG Closed connection 362
15:39:21 INFO - 1500417561325 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49780
15:39:21 INFO - 1500417561326 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:21 INFO - 1500417561327 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561329 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561332 Marionette TRACE 363 <- [1,1,null,{"sessionId":"3ba61790-ee24-4b05-b8d9-e69891271dea","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561334 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
15:39:21 INFO - 1500417561336 Marionette TRACE 363 <- [1,2,null,{}]
15:39:21 INFO - 1500417561338 Marionette DEBUG Closed connection 363
15:39:21 INFO - 1500417561338 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49781
15:39:21 INFO - 1500417561339 Marionette DEBUG Closed connection 364
15:39:21 INFO - 1500417561340 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49782
15:39:21 INFO - 1500417561341 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:39:21 INFO - 1500417561342 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561346 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561350 Marionette TRACE 365 <- [1,1,null,{"sessionId":"0115211d-5faf-4bf5-bcc7-d6bd6abd7b58","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561353 Marionette TRACE 365 -> [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"}]
15:39:21 INFO - 1500417561354 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49340/test.html"
15:39:21 INFO - 1500417561356 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49340/test.html"
15:39:21 INFO - 1500417561356 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49340/test.html"
15:39:21 INFO - 1500417561381 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"
15:39:21 INFO - 1500417561383 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"
15:39:21 INFO - 1500417561387 Marionette TRACE 365 <- [1,2,null,{}]
15:39:21 INFO - 1500417561388 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:39:21 INFO - 1500417561391 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a65485af-a9e6-4da5-b85e-58b4b563c93e","ELEMENT":"a65485af-a9e6-4da5-b85e-58b4b563c93e"}}]
15:39:21 INFO - 1500417561392 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"a65485af-a9e6-4da5-b85e-58b4b563c93e"}]
15:39:21 INFO - 1500417561643 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:39:21 INFO - 1500417561643 Marionette TRACE 365 <- [1,4,null,{}]
15:39:21 INFO - 1500417561645 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"a65485af-a9e6-4da5-b85e-58b4b563c93e","name":"selected"}]
15:39:21 INFO - 1500417561649 Marionette TRACE 365 <- [1,5,null,{"value":true}]
15:39:21 INFO - 1500417561651 Marionette TRACE 365 -> [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}]
15:39:21 INFO - TEST-END: test_click.py TestClick.test_container_element1500417561653 Marionette TRACE 365 <- [1,6,null,{"value":null}]
15:39:21 INFO - 1500417561654 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
15:39:21 INFO - 1500417561655 Marionette TRACE 365 <- [1,7,null,{}]
15:39:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 358ms
15:39:21 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:39:21 INFO - 1500417561659 Marionette DEBUG Closed connection 365
15:39:21 INFO - 1500417561659 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49783
15:39:21 INFO - 1500417561660 Marionette DEBUG Closed connection 366
15:39:21 INFO - 1500417561661 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49784
15:39:21 INFO - 1500417561662 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:21 INFO - 1500417561662 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561665 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561668 Marionette TRACE 367 <- [1,1,null,{"sessionId":"e8d70f84-b56f-446f-adf7-e031088d0973","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561670 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
15:39:21 INFO - 1500417561671 Marionette TRACE 367 <- [1,2,null,{}]
15:39:21 INFO - 1500417561672 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:39:21 INFO - 1500417561672 Marionette TRACE 367 <- [1,3,null,{}]
15:39:21 INFO - 1500417561674 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
15:39:21 INFO - 1500417561675 Marionette TRACE 367 <- [1,4,null,{}]
15:39:21 INFO - 1500417561677 Marionette TRACE 367 -> [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}]
15:39:21 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500417561680 Marionette TRACE 367 <- [1,5,null,{"value":null}]
15:39:21 INFO - 1500417561681 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
15:39:21 INFO - 1500417561683 Marionette TRACE 367 <- [1,6,null,{}]
15:39:21 INFO - 1500417561685 Marionette DEBUG Closed connection 367
15:39:21 INFO - 1500417561685 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49785
15:39:21 INFO - 1500417561686 Marionette DEBUG Closed connection 368
15:39:21 INFO - 1500417561687 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49786
15:39:21 INFO - 1500417561688 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:21 INFO - 1500417561688 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561691 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561696 Marionette TRACE 369 <- [1,1,null,{"sessionId":"023085db-24be-42ef-93e8-96b083d2a628","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561699 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
15:39:21 INFO - 1500417561700 Marionette TRACE 369 <- [1,2,null,{}]
15:39:21 INFO - 1500417561702 Marionette DEBUG Closed connection 369
15:39:21 INFO - 1500417561703 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49787
15:39:21 INFO - 1500417561704 Marionette DEBUG Closed connection 370
15:39:21 INFO - 1500417561704 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49788
15:39:21 INFO - 1500417561705 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:39:21 INFO - 1500417561706 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:21 INFO - 1500417561709 Marionette DEBUG Register listener.js for window 15
15:39:21 INFO - 1500417561719 Marionette TRACE 371 <- [1,1,null,{"sessionId":"649bb36a-cdcf-4d99-bdad-e2872114f800","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:21 INFO - 1500417561721 Marionette TRACE 371 -> [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"}]
15:39:21 INFO - 1500417561723 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"
15:39:21 INFO - 1500417561725 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"
15:39:21 INFO - 1500417561725 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"
15:39:21 INFO - 1500417561746 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"
15:39:21 INFO - 1500417561748 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"
15:39:21 INFO - 1500417561752 Marionette TRACE 371 <- [1,2,null,{}]
15:39:21 INFO - 1500417561753 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:39:21 INFO - 1500417561757 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c4e5b9e1-965e-4c70-bd54-ca478f0b71bc","ELEMENT":"c4e5b9e1-965e-4c70-bd54-ca478f0b71bc"}}]
15:39:21 INFO - 1500417561758 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"c4e5b9e1-965e-4c70-bd54-ca478f0b71bc"}]
15:39:22 INFO - 1500417562014 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:39:22 INFO - 1500417562014 Marionette TRACE 371 <- [1,4,null,{}]
15:39:22 INFO - 1500417562016 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"c4e5b9e1-965e-4c70-bd54-ca478f0b71bc","name":"selected"}]
15:39:22 INFO - 1500417562020 Marionette TRACE 371 <- [1,5,null,{"value":true}]
15:39:22 INFO - 1500417562022 Marionette TRACE 371 -> [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}]
15:39:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500417562025 Marionette TRACE 371 <- [1,6,null,{"value":null}]
15:39:22 INFO - 1500417562026 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
15:39:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 371ms
15:39:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:39:22 INFO - 1500417562027 Marionette TRACE 371 <- [1,7,null,{}]
15:39:22 INFO - 1500417562030 Marionette DEBUG Closed connection 371
15:39:22 INFO - 1500417562030 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49789
15:39:22 INFO - 1500417562031 Marionette DEBUG Closed connection 372
15:39:22 INFO - 1500417562031 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49790
15:39:22 INFO - 1500417562033 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:22 INFO - 1500417562033 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562036 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562039 Marionette TRACE 373 <- [1,1,null,{"sessionId":"11b35d51-3b9c-4585-9cfe-321debd84ddb","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562041 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
15:39:22 INFO - 1500417562041 Marionette TRACE 373 <- [1,2,null,{}]
15:39:22 INFO - 1500417562043 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:39:22 INFO - 1500417562043 Marionette TRACE 373 <- [1,3,null,{}]
15:39:22 INFO - 1500417562044 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
15:39:22 INFO - 1500417562044 Marionette TRACE 373 <- [1,4,null,{}]
15:39:22 INFO - 1500417562047 Marionette TRACE 373 -> [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}]
15:39:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500417562049 Marionette TRACE 373 <- [1,5,null,{"value":null}]
15:39:22 INFO - 1500417562050 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
15:39:22 INFO - 1500417562052 Marionette TRACE 373 <- [1,6,null,{}]
15:39:22 INFO - 1500417562054 Marionette DEBUG Closed connection 373
15:39:22 INFO - 1500417562054 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49791
15:39:22 INFO - 1500417562055 Marionette DEBUG Closed connection 374
15:39:22 INFO - 1500417562056 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49792
15:39:22 INFO - 1500417562057 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:22 INFO - 1500417562057 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562060 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562063 Marionette TRACE 375 <- [1,1,null,{"sessionId":"ceb83c2f-f666-49fb-b84a-4b9d902b016d","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562065 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
15:39:22 INFO - 1500417562066 Marionette TRACE 375 <- [1,2,null,{}]
15:39:22 INFO - 1500417562068 Marionette DEBUG Closed connection 375
15:39:22 INFO - 1500417562069 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49793
15:39:22 INFO - 1500417562069 Marionette DEBUG Closed connection 376
15:39:22 INFO - 1500417562070 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49794
15:39:22 INFO - 1500417562071 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:39:22 INFO - 1500417562072 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562074 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562077 Marionette TRACE 377 <- [1,1,null,{"sessionId":"16cced96-1382-4c16-86ed-b65bf02e8bde","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562079 Marionette TRACE 377 -> [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"}]
15:39:22 INFO - 1500417562081 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"
15:39:22 INFO - 1500417562083 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"
15:39:22 INFO - 1500417562083 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"
15:39:22 INFO - 1500417562102 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"
15:39:22 INFO - 1500417562105 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"
15:39:22 INFO - 1500417562108 Marionette TRACE 377 <- [1,2,null,{}]
15:39:22 INFO - 1500417562116 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:39:22 INFO - 1500417562118 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b980019b-c524-40bd-a59b-633a5045a6f5","ELEMENT":"b980019b-c524-40bd-a59b-633a5045a6f5"}}]
15:39:22 INFO - 1500417562119 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"b980019b-c524-40bd-a59b-633a5045a6f5"}]
15:39:22 INFO - 1500417562368 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:39:22 INFO - 1500417562368 Marionette TRACE 377 <- [1,4,null,{}]
15:39:22 INFO - 1500417562370 Marionette TRACE 377 -> [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}]
15:39:22 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500417562373 Marionette TRACE 377 <- [1,5,null,{"value":null}]
15:39:22 INFO - 1500417562374 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
15:39:22 INFO - 1500417562375 Marionette TRACE 377 <- [1,6,null,{}]
15:39:22 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 348ms
15:39:22 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:39:22 INFO - 1500417562377 Marionette DEBUG Closed connection 377
15:39:22 INFO - 1500417562378 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49795
15:39:22 INFO - 1500417562379 Marionette DEBUG Closed connection 378
15:39:22 INFO - 1500417562379 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49796
15:39:22 INFO - 1500417562380 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:22 INFO - 1500417562381 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562384 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562387 Marionette TRACE 379 <- [1,1,null,{"sessionId":"1822af70-cdd1-416f-bcc9-21b598175655","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562389 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
15:39:22 INFO - 1500417562390 Marionette TRACE 379 <- [1,2,null,{}]
15:39:22 INFO - 1500417562391 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:39:22 INFO - 1500417562391 Marionette TRACE 379 <- [1,3,null,{}]
15:39:22 INFO - 1500417562392 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
15:39:22 INFO - 1500417562393 Marionette TRACE 379 <- [1,4,null,{}]
15:39:22 INFO - 1500417562394 Marionette TRACE 379 -> [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}]
15:39:22 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500417562396 Marionette TRACE 379 <- [1,5,null,{"value":null}]
15:39:22 INFO - 1500417562397 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
15:39:22 INFO - 1500417562399 Marionette TRACE 379 <- [1,6,null,{}]
15:39:22 INFO - 1500417562401 Marionette DEBUG Closed connection 379
15:39:22 INFO - 1500417562401 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49797
15:39:22 INFO - 1500417562402 Marionette DEBUG Closed connection 380
15:39:22 INFO - 1500417562403 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49798
15:39:22 INFO - 1500417562404 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:22 INFO - 1500417562404 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562407 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562410 Marionette TRACE 381 <- [1,1,null,{"sessionId":"b46e88cd-16cf-4ecd-ac42-a65c1ae5f7a2","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562412 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
15:39:22 INFO - 1500417562413 Marionette TRACE 381 <- [1,2,null,{}]
15:39:22 INFO - 1500417562416 Marionette DEBUG Closed connection 381
15:39:22 INFO - 1500417562416 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49799
15:39:22 INFO - 1500417562417 Marionette DEBUG Closed connection 382
15:39:22 INFO - 1500417562418 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49800
15:39:22 INFO - 1500417562419 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:39:22 INFO - 1500417562419 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562422 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562425 Marionette TRACE 383 <- [1,1,null,{"sessionId":"7ec0553b-a389-4cde-9dcd-648cadaa21f3","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562428 Marionette TRACE 383 -> [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"}]
15:39:22 INFO - 1500417562430 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"
15:39:22 INFO - 1500417562431 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"
15:39:22 INFO - 1500417562432 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"
15:39:22 INFO - 1500417562451 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"
15:39:22 INFO - 1500417562453 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"
15:39:22 INFO - 1500417562457 Marionette TRACE 383 <- [1,2,null,{}]
15:39:22 INFO - 1500417562458 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:39:22 INFO - 1500417562468 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ba9a2423-8414-4fbf-99aa-d16c9dc51ed5","ELEMENT":"ba9a2423-8414-4fbf-99aa-d16c9dc51ed5"}}]
15:39:22 INFO - 1500417562469 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"ba9a2423-8414-4fbf-99aa-d16c9dc51ed5"}]
15:39:22 INFO - 1500417562714 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:39:22 INFO - 1500417562714 Marionette TRACE 383 <- [1,4,null,{}]
15:39:22 INFO - 1500417562716 Marionette TRACE 383 -> [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}]
15:39:22 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500417562719 Marionette TRACE 383 <- [1,5,null,{"value":null}]
15:39:22 INFO - 1500417562720 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
15:39:22 INFO - 1500417562721 Marionette TRACE 383 <- [1,6,null,{}]
15:39:22 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 345ms
15:39:22 INFO - TEST-START | test_click.py TestClick.test_input_file
15:39:22 INFO - 1500417562723 Marionette DEBUG Closed connection 383
15:39:22 INFO - 1500417562724 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49801
15:39:22 INFO - 1500417562725 Marionette DEBUG Closed connection 384
15:39:22 INFO - 1500417562725 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49802
15:39:22 INFO - 1500417562726 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:22 INFO - 1500417562727 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562730 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562733 Marionette TRACE 385 <- [1,1,null,{"sessionId":"ba8dd670-c37f-4519-a313-2d4a1bca6287","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562735 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
15:39:22 INFO - 1500417562735 Marionette TRACE 385 <- [1,2,null,{}]
15:39:22 INFO - 1500417562737 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:39:22 INFO - 1500417562737 Marionette TRACE 385 <- [1,3,null,{}]
15:39:22 INFO - 1500417562738 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
15:39:22 INFO - 1500417562739 Marionette TRACE 385 <- [1,4,null,{}]
15:39:22 INFO - 1500417562741 Marionette TRACE 385 -> [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}]
15:39:22 INFO - TEST-START: test_click.py TestClick.test_input_file1500417562743 Marionette TRACE 385 <- [1,5,null,{"value":null}]
15:39:22 INFO - 1500417562744 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
15:39:22 INFO - 1500417562745 Marionette TRACE 385 <- [1,6,null,{}]
15:39:22 INFO - 1500417562748 Marionette DEBUG Closed connection 385
15:39:22 INFO - 1500417562748 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49803
15:39:22 INFO - 1500417562749 Marionette DEBUG Closed connection 386
15:39:22 INFO - 1500417562750 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49804
15:39:22 INFO - 1500417562751 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:22 INFO - 1500417562752 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562754 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562757 Marionette TRACE 387 <- [1,1,null,{"sessionId":"740f3b77-3fea-4d71-bebf-7b2bbcfa0f46","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562759 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
15:39:22 INFO - 1500417562761 Marionette TRACE 387 <- [1,2,null,{}]
15:39:22 INFO - 1500417562763 Marionette DEBUG Closed connection 387
15:39:22 INFO - 1500417562763 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49805
15:39:22 INFO - 1500417562764 Marionette DEBUG Closed connection 388
15:39:22 INFO - 1500417562765 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49806
15:39:22 INFO - 1500417562766 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:39:22 INFO - 1500417562767 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562770 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562773 Marionette TRACE 389 <- [1,1,null,{"sessionId":"324514ee-de5f-4bb4-8f54-fed5fe03553f","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562775 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:39:22 INFO - 1500417562776 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"
15:39:22 INFO - 1500417562778 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"
15:39:22 INFO - 1500417562779 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"
15:39:22 INFO - 1500417562790 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:39:22 INFO - 1500417562801 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:39:22 INFO - 1500417562805 Marionette TRACE 389 <- [1,2,null,{}]
15:39:22 INFO - 1500417562806 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:39:22 INFO - 1500417562809 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5bf68275-12e1-4830-b825-547e8627dc8c","ELEMENT":"5bf68275-12e1-4830-b825-547e8627dc8c"}}]
15:39:22 INFO - 1500417562815 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"5bf68275-12e1-4830-b825-547e8627dc8c"}]
15:39:22 INFO - 1500417562851 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
15:39:22 INFO - 1500417562853 Marionette TRACE 389 -> [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}]
15:39:22 INFO - TEST-END: test_click.py TestClick.test_input_file1500417562855 Marionette TRACE 389 <- [1,5,null,{"value":null}]
15:39:22 INFO - 1500417562856 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
15:39:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 136ms
15:39:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:39:22 INFO - 1500417562858 Marionette TRACE 389 <- [1,6,null,{}]
15:39:22 INFO - 1500417562860 Marionette DEBUG Closed connection 389
15:39:22 INFO - 1500417562860 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49807
15:39:22 INFO - 1500417562862 Marionette DEBUG Closed connection 390
15:39:22 INFO - 1500417562863 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49808
15:39:22 INFO - 1500417562864 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:22 INFO - 1500417562865 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562868 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562871 Marionette TRACE 391 <- [1,1,null,{"sessionId":"07803ea9-5492-4225-b507-02ffdfb85b62","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562873 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
15:39:22 INFO - 1500417562873 Marionette TRACE 391 <- [1,2,null,{}]
15:39:22 INFO - 1500417562874 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:39:22 INFO - 1500417562875 Marionette TRACE 391 <- [1,3,null,{}]
15:39:22 INFO - 1500417562876 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
15:39:22 INFO - 1500417562876 Marionette TRACE 391 <- [1,4,null,{}]
15:39:22 INFO - 1500417562878 Marionette TRACE 391 -> [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}]
15:39:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500417562880 Marionette TRACE 391 <- [1,5,null,{"value":null}]
15:39:22 INFO - 1500417562881 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
15:39:22 INFO - 1500417562883 Marionette TRACE 391 <- [1,6,null,{}]
15:39:22 INFO - 1500417562885 Marionette DEBUG Closed connection 391
15:39:22 INFO - 1500417562886 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49809
15:39:22 INFO - 1500417562886 Marionette DEBUG Closed connection 392
15:39:22 INFO - 1500417562887 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49810
15:39:22 INFO - 1500417562888 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:22 INFO - 1500417562889 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562892 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562908 Marionette TRACE 393 <- [1,1,null,{"sessionId":"c6d94309-adc3-48a4-8001-77eacc5a688c","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562916 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
15:39:22 INFO - 1500417562918 Marionette TRACE 393 <- [1,2,null,{}]
15:39:22 INFO - 1500417562921 Marionette DEBUG Closed connection 393
15:39:22 INFO - 1500417562922 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49811
15:39:22 INFO - 1500417562971 Marionette DEBUG Closed connection 394
15:39:22 INFO - 1500417562972 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49812
15:39:22 INFO - 1500417562974 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:39:22 INFO - 1500417562975 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:22 INFO - 1500417562978 Marionette DEBUG Register listener.js for window 15
15:39:22 INFO - 1500417562984 Marionette TRACE 395 <- [1,1,null,{"sessionId":"82630f77-6d2d-4ca9-9dab-0e370535f6dc","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:22 INFO - 1500417562989 Marionette TRACE 395 -> [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"}]
15:39:22 INFO - 1500417562991 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:39:22 INFO - 1500417562994 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:39:22 INFO - 1500417562994 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:39:23 INFO - 1500417563019 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"
15:39:23 INFO - 1500417563022 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"
15:39:23 INFO - 1500417563027 Marionette TRACE 395 <- [1,2,null,{}]
15:39:23 INFO - 1500417563117 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:39:23 INFO - 1500417563122 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"980081c8-49e3-4987-b793-88f39ffa0647","ELEMENT":"980081c8-49e3-4987-b793-88f39ffa0647"}}]
15:39:23 INFO - 1500417563124 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:39:23 INFO - 1500417563128 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d1fc7035-ea30-49c9-a526-93236730c346","ELEMENT":"d1fc7035-ea30-49c9-a526-93236730c346"}}]
15:39:23 INFO - 1500417563129 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"980081c8-49e3-4987-b793-88f39ffa0647"}]
15:39:23 INFO - 1500417563380 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:39:23 INFO - 1500417563381 Marionette TRACE 395 <- [1,5,null,{}]
15:39:23 INFO - 1500417563383 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"d1fc7035-ea30-49c9-a526-93236730c346"}]
15:39:23 INFO - 1500417563402 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
15:39:23 INFO - 1500417563404 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
15:39:23 INFO - 1500417563408 Marionette TRACE 395 <- [1,7,null,{"value":false}]
15:39:23 INFO - 1500417563412 Marionette TRACE 395 -> [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}]
15:39:23 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500417563416 Marionette TRACE 395 <- [1,8,null,{"value":null}]
15:39:23 INFO - 1500417563418 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
15:39:23 INFO - 1500417563420 Marionette TRACE 395 <- [1,9,null,{}]
15:39:23 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 562ms
15:39:23 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:39:23 INFO - 1500417563422 Marionette DEBUG Closed connection 395
15:39:23 INFO - 1500417563423 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49813
15:39:23 INFO - 1500417563424 Marionette DEBUG Closed connection 396
15:39:23 INFO - 1500417563425 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49814
15:39:23 INFO - 1500417563426 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:23 INFO - 1500417563429 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:23 INFO - 1500417563431 Marionette DEBUG Register listener.js for window 15
15:39:23 INFO - 1500417563437 Marionette TRACE 397 <- [1,1,null,{"sessionId":"8b060e20-1584-4d7d-a36c-8efeffc13a86","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:23 INFO - 1500417563441 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
15:39:23 INFO - 1500417563441 Marionette TRACE 397 <- [1,2,null,{}]
15:39:23 INFO - 1500417563443 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:39:23 INFO - 1500417563443 Marionette TRACE 397 <- [1,3,null,{}]
15:39:23 INFO - 1500417563446 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
15:39:23 INFO - 1500417563448 Marionette TRACE 397 <- [1,4,null,{}]
15:39:23 INFO - 1500417563449 Marionette TRACE 397 -> [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}]
15:39:23 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500417563453 Marionette TRACE 397 <- [1,5,null,{"value":null}]
15:39:23 INFO - 1500417563454 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
15:39:23 INFO - 1500417563456 Marionette TRACE 397 <- [1,6,null,{}]
15:39:23 INFO - 1500417563459 Marionette DEBUG Closed connection 397
15:39:23 INFO - 1500417563460 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49815
15:39:23 INFO - 1500417563461 Marionette DEBUG Closed connection 398
15:39:23 INFO - 1500417563461 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49816
15:39:23 INFO - 1500417563463 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:39:23 INFO - 1500417563464 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:23 INFO - 1500417563467 Marionette DEBUG Register listener.js for window 15
15:39:23 INFO - 1500417563471 Marionette TRACE 399 <- [1,1,null,{"sessionId":"2f1e7013-c179-4796-b619-778ba4fe8550","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:23 INFO - 1500417563474 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
15:39:23 INFO - 1500417563476 Marionette TRACE 399 <- [1,2,null,{}]
15:39:23 INFO - 1500417563479 Marionette DEBUG Closed connection 399
15:39:23 INFO - 1500417563479 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49817
15:39:23 INFO - 1500417563481 Marionette DEBUG Closed connection 400
15:39:23 INFO - 1500417563481 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49818
15:39:23 INFO - 1500417563482 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:39:23 INFO - 1500417563484 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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}
15:39:23 INFO - 1500417563486 Marionette DEBUG Register listener.js for window 15
15:39:23 INFO - 1500417563491 Marionette TRACE 401 <- [1,1,null,{"sessionId":"4e7ac582-a940-460f-bf15-f7f2bbb584fd","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":444,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-020.001\\AppData\\Local\\Temp\\tmpczx5tk.mozrunner","moz:accessibilityChecks":false}}]
15:39:23 INFO - 1500417563493 Marionette TRACE 401 -> [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"}]
15:39:23 INFO - 1500417563496 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"
15:39:23 INFO - 1500417563499 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"
15:39:23 INFO - 1500417563499 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"
15:39:23 INFO - 1500417563521 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"
15:39:23 INFO - 1500417563524 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"
15:39:23 INFO - 1500417563534 Marionette TRACE 401 <- [1,2,null,{}]
15:39:23 INFO - 1500417563536 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:39:23 INFO - 1500417563539 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3c89f1de-7124-4d39-9ed0-6831c2c5816b","ELEMENT":"3c89f1de-7124-4d39-9ed0-6831c2c5816b"}}]
15:39:23 INFO - 1500417563540 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"3c89f1de-7124-4d39-9ed0-6831c2c5816b"}]
15:39:23 INFO - 1500417563567 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
15:39:23 INFO - 1500417563568 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"3c89f1de-7124-4d39-9ed0-6831c2c5816b"}]
15:39:23 INFO - 1500417563590 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element