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]
18:20:38 INFO - 1499304038019 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}]
18:20:38 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499304038022 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:20:38 INFO - 1499304038023 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:20:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 147ms
18:20:38 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:20:38 INFO - 1499304038025 Marionette TRACE 352 <- [1,6,null,{}]
18:20:38 INFO - 1499304038026 Marionette DEBUG Closed connection 352
18:20:38 INFO - 1499304038027 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50347
18:20:38 INFO - 1499304038028 Marionette DEBUG Closed connection 353
18:20:38 INFO - 1499304038029 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50348
18:20:38 INFO - 1499304038030 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:38 INFO - 1499304038031 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038034 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038037 Marionette TRACE 354 <- [1,1,null,{"sessionId":"dd482db2-0621-4c4e-8002-5f7a5d0ba6fe","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038038 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:20:38 INFO - 1499304038039 Marionette TRACE 354 <- [1,2,null,{}]
18:20:38 INFO - 1499304038040 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:20:38 INFO - 1499304038041 Marionette TRACE 354 <- [1,3,null,{}]
18:20:38 INFO - 1499304038042 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:20:38 INFO - 1499304038042 Marionette TRACE 354 <- [1,4,null,{}]
18:20:38 INFO - 1499304038044 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}]
18:20:38 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499304038047 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:20:38 INFO - 1499304038048 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:20:38 INFO - 1499304038049 Marionette TRACE 354 <- [1,6,null,{}]
18:20:38 INFO - 1499304038051 Marionette DEBUG Closed connection 354
18:20:38 INFO - 1499304038052 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50349
18:20:38 INFO - 1499304038053 Marionette DEBUG Closed connection 355
18:20:38 INFO - 1499304038053 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50350
18:20:38 INFO - 1499304038055 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:38 INFO - 1499304038055 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038058 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038061 Marionette TRACE 356 <- [1,1,null,{"sessionId":"cb8e3837-d041-4603-8590-9046e56dadc5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038062 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:20:38 INFO - 1499304038064 Marionette TRACE 356 <- [1,2,null,{}]
18:20:38 INFO - 1499304038066 Marionette DEBUG Closed connection 356
18:20:38 INFO - 1499304038066 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50351
18:20:38 INFO - 1499304038067 Marionette DEBUG Closed connection 357
18:20:38 INFO - 1499304038068 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50352
18:20:38 INFO - 1499304038069 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:20:38 INFO - 1499304038070 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038073 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038076 Marionette TRACE 358 <- [1,1,null,{"sessionId":"7f4ca300-d801-4c06-ac49-b5c8b6d9b110","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038077 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49904/clicks.html"}]
18:20:38 INFO - 1499304038079 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49904/hidden.html"
18:20:38 INFO - 1499304038089 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49904/hidden.html"
18:20:38 INFO - 1499304038089 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49904/hidden.html"
18:20:38 INFO - 1499304038106 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49904/clicks.html"
18:20:38 INFO - 1499304038108 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49904/clicks.html"
18:20:38 INFO - 1499304038117 Marionette TRACE 358 <- [1,2,null,{}]
18:20:38 INFO - 1499304038124 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:20:38 INFO - 1499304038128 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dd489a85-0582-4269-9c10-510f99bf7d0b","ELEMENT":"dd489a85-0582-4269-9c10-510f99bf7d0b"}}]
18:20:38 INFO - 1499304038129 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"dd489a85-0582-4269-9c10-510f99bf7d0b"}]
18:20:38 INFO - 1499304038175 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49904/clicks.html"
18:20:38 INFO - 1499304038188 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49904/clicks.html"
18:20:38 INFO - 1499304038188 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49904/clicks.html"
18:20:38 INFO - 1499304038201 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49904/test.html"
18:20:38 INFO - 1499304038205 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49904/test.html"
18:20:38 INFO - 1499304038206 Marionette TRACE 358 <- [1,4,null,{}]
18:20:38 INFO - 1499304038210 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:20:38 INFO - 1499304038217 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"44a7f802-1fcf-4cdf-88b7-aa6b29392f89","ELEMENT":"44a7f802-1fcf-4cdf-88b7-aa6b29392f89"}}]
18:20:38 INFO - 1499304038219 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:20:38 INFO - 1499304038219 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:20:38 INFO - 1499304038221 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}]
18:20:38 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499304038224 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:20:38 INFO - 1499304038226 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:20:38 INFO - 1499304038227 Marionette TRACE 358 <- [1,8,null,{}]
18:20:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 202ms
18:20:38 INFO - TEST-START | test_click.py TestClick.test_container_element
18:20:38 INFO - 1499304038229 Marionette DEBUG Closed connection 358
18:20:38 INFO - 1499304038230 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50355
18:20:38 INFO - 1499304038231 Marionette DEBUG Closed connection 359
18:20:38 INFO - 1499304038232 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50356
18:20:38 INFO - 1499304038233 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:38 INFO - 1499304038234 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038237 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038241 Marionette TRACE 360 <- [1,1,null,{"sessionId":"e29c59a0-4713-4bee-8756-db9b3b83f534","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038242 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:20:38 INFO - 1499304038243 Marionette TRACE 360 <- [1,2,null,{}]
18:20:38 INFO - 1499304038244 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:20:38 INFO - 1499304038244 Marionette TRACE 360 <- [1,3,null,{}]
18:20:38 INFO - 1499304038245 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:20:38 INFO - 1499304038246 Marionette TRACE 360 <- [1,4,null,{}]
18:20:38 INFO - 1499304038248 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}]
18:20:38 INFO - TEST-START: test_click.py TestClick.test_container_element1499304038251 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:20:38 INFO - 1499304038252 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:20:38 INFO - 1499304038253 Marionette TRACE 360 <- [1,6,null,{}]
18:20:38 INFO - 1499304038255 Marionette DEBUG Closed connection 360
18:20:38 INFO - 1499304038256 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50357
18:20:38 INFO - 1499304038257 Marionette DEBUG Closed connection 361
18:20:38 INFO - 1499304038257 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50358
18:20:38 INFO - 1499304038258 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:38 INFO - 1499304038259 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038262 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038265 Marionette TRACE 362 <- [1,1,null,{"sessionId":"27c8243f-cb6a-4795-8e81-030f110ca082","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038266 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:20:38 INFO - 1499304038268 Marionette TRACE 362 <- [1,2,null,{}]
18:20:38 INFO - 1499304038269 Marionette DEBUG Closed connection 362
18:20:38 INFO - 1499304038270 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50359
18:20:38 INFO - 1499304038271 Marionette DEBUG Closed connection 363
18:20:38 INFO - 1499304038272 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50360
18:20:38 INFO - 1499304038273 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:20:38 INFO - 1499304038273 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038276 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038280 Marionette TRACE 364 <- [1,1,null,{"sessionId":"08da6213-ad88-4ed8-ade3-b561227470e5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038281 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"}]
18:20:38 INFO - 1499304038283 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49904/test.html"
18:20:38 INFO - 1499304038286 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49904/test.html"
18:20:38 INFO - 1499304038286 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49904/test.html"
18:20:38 INFO - 1499304038293 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"
18:20:38 INFO - 1499304038297 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"
18:20:38 INFO - 1499304038309 Marionette TRACE 364 <- [1,2,null,{}]
18:20:38 INFO - 1499304038320 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:20:38 INFO - 1499304038324 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"da33588d-6419-4657-b543-fdb5a14fc7ac","ELEMENT":"da33588d-6419-4657-b543-fdb5a14fc7ac"}}]
18:20:38 INFO - 1499304038325 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"da33588d-6419-4657-b543-fdb5a14fc7ac"}]
18:20:38 INFO - 1499304038574 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:20:38 INFO - 1499304038575 Marionette TRACE 364 <- [1,4,null,{}]
18:20:38 INFO - 1499304038576 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"da33588d-6419-4657-b543-fdb5a14fc7ac","name":"selected"}]
18:20:38 INFO - 1499304038581 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:20:38 INFO - 1499304038583 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}]
18:20:38 INFO - TEST-END: test_click.py TestClick.test_container_element1499304038586 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:20:38 INFO - 1499304038587 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:20:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 362ms
18:20:38 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:20:38 INFO - 1499304038589 Marionette TRACE 364 <- [1,7,null,{}]
18:20:38 INFO - 1499304038591 Marionette DEBUG Closed connection 364
18:20:38 INFO - 1499304038592 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50361
18:20:38 INFO - 1499304038592 Marionette DEBUG Closed connection 365
18:20:38 INFO - 1499304038594 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50362
18:20:38 INFO - 1499304038595 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:38 INFO - 1499304038595 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038598 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038602 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1d823113-2adb-4845-b1f7-037264d56c00","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038603 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:20:38 INFO - 1499304038603 Marionette TRACE 366 <- [1,2,null,{}]
18:20:38 INFO - 1499304038604 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:20:38 INFO - 1499304038605 Marionette TRACE 366 <- [1,3,null,{}]
18:20:38 INFO - 1499304038606 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:20:38 INFO - 1499304038606 Marionette TRACE 366 <- [1,4,null,{}]
18:20:38 INFO - 1499304038608 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}]
18:20:38 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499304038611 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:20:38 INFO - 1499304038612 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:20:38 INFO - 1499304038614 Marionette TRACE 366 <- [1,6,null,{}]
18:20:38 INFO - 1499304038615 Marionette DEBUG Closed connection 366
18:20:38 INFO - 1499304038616 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50363
18:20:38 INFO - 1499304038617 Marionette DEBUG Closed connection 367
18:20:38 INFO - 1499304038618 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50364
18:20:38 INFO - 1499304038619 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:38 INFO - 1499304038620 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038623 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038626 Marionette TRACE 368 <- [1,1,null,{"sessionId":"1d089098-4221-4450-a627-791d5678d2d7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038627 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:20:38 INFO - 1499304038629 Marionette TRACE 368 <- [1,2,null,{}]
18:20:38 INFO - 1499304038631 Marionette DEBUG Closed connection 368
18:20:38 INFO - 1499304038631 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50365
18:20:38 INFO - 1499304038632 Marionette DEBUG Closed connection 369
18:20:38 INFO - 1499304038633 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50366
18:20:38 INFO - 1499304038634 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:20:38 INFO - 1499304038635 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038638 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038641 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d2c467ab-7f87-471f-8e64-431234baf54e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038642 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"}]
18:20:38 INFO - 1499304038644 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"
18:20:38 INFO - 1499304038647 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"
18:20:38 INFO - 1499304038647 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"
18:20:38 INFO - 1499304038654 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"
18:20:38 INFO - 1499304038658 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"
18:20:38 INFO - 1499304038661 Marionette TRACE 370 <- [1,2,null,{}]
18:20:38 INFO - 1499304038671 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:20:38 INFO - 1499304038675 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"11924d0e-0237-4744-9756-400334b6e18d","ELEMENT":"11924d0e-0237-4744-9756-400334b6e18d"}}]
18:20:38 INFO - 1499304038676 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"11924d0e-0237-4744-9756-400334b6e18d"}]
18:20:38 INFO - 1499304038923 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:20:38 INFO - 1499304038924 Marionette TRACE 370 <- [1,4,null,{}]
18:20:38 INFO - 1499304038925 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"11924d0e-0237-4744-9756-400334b6e18d","name":"selected"}]
18:20:38 INFO - 1499304038930 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:20:38 INFO - 1499304038932 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}]
18:20:38 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499304038935 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:20:38 INFO - 1499304038936 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:20:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 348ms
18:20:38 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:20:38 INFO - 1499304038938 Marionette TRACE 370 <- [1,7,null,{}]
18:20:38 INFO - 1499304038939 Marionette DEBUG Closed connection 370
18:20:38 INFO - 1499304038940 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50367
18:20:38 INFO - 1499304038941 Marionette DEBUG Closed connection 371
18:20:38 INFO - 1499304038942 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50368
18:20:38 INFO - 1499304038943 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:38 INFO - 1499304038943 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038946 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038950 Marionette TRACE 372 <- [1,1,null,{"sessionId":"fee7985d-8d5b-4b15-b72e-ee614ffdd505","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038951 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:20:38 INFO - 1499304038951 Marionette TRACE 372 <- [1,2,null,{}]
18:20:38 INFO - 1499304038952 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:20:38 INFO - 1499304038953 Marionette TRACE 372 <- [1,3,null,{}]
18:20:38 INFO - 1499304038954 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:20:38 INFO - 1499304038954 Marionette TRACE 372 <- [1,4,null,{}]
18:20:38 INFO - 1499304038956 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}]
18:20:38 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499304038959 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:20:38 INFO - 1499304038960 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:20:38 INFO - 1499304038962 Marionette TRACE 372 <- [1,6,null,{}]
18:20:38 INFO - 1499304038963 Marionette DEBUG Closed connection 372
18:20:38 INFO - 1499304038964 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50369
18:20:38 INFO - 1499304038965 Marionette DEBUG Closed connection 373
18:20:38 INFO - 1499304038966 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50370
18:20:38 INFO - 1499304038967 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:38 INFO - 1499304038967 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038970 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038974 Marionette TRACE 374 <- [1,1,null,{"sessionId":"bdd95604-f042-4723-9c57-e59a5125856b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038975 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:20:38 INFO - 1499304038977 Marionette TRACE 374 <- [1,2,null,{}]
18:20:38 INFO - 1499304038978 Marionette DEBUG Closed connection 374
18:20:38 INFO - 1499304038979 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50371
18:20:38 INFO - 1499304038981 Marionette DEBUG Closed connection 375
18:20:38 INFO - 1499304038982 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50372
18:20:38 INFO - 1499304038983 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:20:38 INFO - 1499304038984 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:38 INFO - 1499304038987 Marionette DEBUG Register listener.js for window 4294967297
18:20:38 INFO - 1499304038990 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8eec106e-7fcd-4b58-a996-cc780559d982","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:38 INFO - 1499304038991 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"}]
18:20:38 INFO - 1499304038993 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"
18:20:38 INFO - 1499304038996 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"
18:20:38 INFO - 1499304038996 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"
18:20:39 INFO - 1499304039005 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"
18:20:39 INFO - 1499304039007 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"
18:20:39 INFO - 1499304039012 Marionette TRACE 376 <- [1,2,null,{}]
18:20:39 INFO - 1499304039022 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:20:39 INFO - 1499304039026 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86ec7edb-0d17-4329-9c22-b2260a37d1af","ELEMENT":"86ec7edb-0d17-4329-9c22-b2260a37d1af"}}]
18:20:39 INFO - 1499304039027 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"86ec7edb-0d17-4329-9c22-b2260a37d1af"}]
18:20:39 INFO - 1499304039270 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:20:39 INFO - 1499304039271 Marionette TRACE 376 <- [1,4,null,{}]
18:20:39 INFO - 1499304039273 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}]
18:20:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499304039276 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:20:39 INFO - 1499304039277 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:20:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 341ms
18:20:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:20:39 INFO - 1499304039279 Marionette TRACE 376 <- [1,6,null,{}]
18:20:39 INFO - 1499304039281 Marionette DEBUG Closed connection 376
18:20:39 INFO - 1499304039283 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50373
18:20:39 INFO - 1499304039284 Marionette DEBUG Closed connection 377
18:20:39 INFO - 1499304039284 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50374
18:20:39 INFO - 1499304039286 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:39 INFO - 1499304039286 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039289 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039293 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4605e106-8964-4e97-b1a8-ee068ed1c783","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039294 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:20:39 INFO - 1499304039294 Marionette TRACE 378 <- [1,2,null,{}]
18:20:39 INFO - 1499304039295 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:20:39 INFO - 1499304039296 Marionette TRACE 378 <- [1,3,null,{}]
18:20:39 INFO - 1499304039297 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:20:39 INFO - 1499304039297 Marionette TRACE 378 <- [1,4,null,{}]
18:20:39 INFO - 1499304039299 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}]
18:20:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499304039302 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:20:39 INFO - 1499304039303 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:20:39 INFO - 1499304039305 Marionette TRACE 378 <- [1,6,null,{}]
18:20:39 INFO - 1499304039307 Marionette DEBUG Closed connection 378
18:20:39 INFO - 1499304039308 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50375
18:20:39 INFO - 1499304039308 Marionette DEBUG Closed connection 379
18:20:39 INFO - 1499304039309 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50376
18:20:39 INFO - 1499304039310 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:39 INFO - 1499304039311 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039314 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039317 Marionette TRACE 380 <- [1,1,null,{"sessionId":"66238f6e-b7a8-4837-9b1d-522619b866be","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039318 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:20:39 INFO - 1499304039320 Marionette TRACE 380 <- [1,2,null,{}]
18:20:39 INFO - 1499304039322 Marionette DEBUG Closed connection 380
18:20:39 INFO - 1499304039322 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50377
18:20:39 INFO - 1499304039323 Marionette DEBUG Closed connection 381
18:20:39 INFO - 1499304039324 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50378
18:20:39 INFO - 1499304039325 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:20:39 INFO - 1499304039326 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039328 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039332 Marionette TRACE 382 <- [1,1,null,{"sessionId":"88c05011-4e51-42ac-89ef-c8cd9e548ae6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039333 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"}]
18:20:39 INFO - 1499304039335 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"
18:20:39 INFO - 1499304039337 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"
18:20:39 INFO - 1499304039338 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"
18:20:39 INFO - 1499304039344 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"
18:20:39 INFO - 1499304039348 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"
18:20:39 INFO - 1499304039356 Marionette TRACE 382 <- [1,2,null,{}]
18:20:39 INFO - 1499304039361 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:20:39 INFO - 1499304039368 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ee3c9512-b562-4cce-9854-466c0f2b5c40","ELEMENT":"ee3c9512-b562-4cce-9854-466c0f2b5c40"}}]
18:20:39 INFO - 1499304039370 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ee3c9512-b562-4cce-9854-466c0f2b5c40"}]
18:20:39 INFO - 1499304039619 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:20:39 INFO - 1499304039620 Marionette TRACE 382 <- [1,4,null,{}]
18:20:39 INFO - 1499304039622 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}]
18:20:39 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499304039625 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:20:39 INFO - 1499304039626 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:20:39 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 348ms
18:20:39 INFO - TEST-START | test_click.py TestClick.test_input_file
18:20:39 INFO - 1499304039627 Marionette TRACE 382 <- [1,6,null,{}]
18:20:39 INFO - 1499304039629 Marionette DEBUG Closed connection 382
18:20:39 INFO - 1499304039630 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50379
18:20:39 INFO - 1499304039631 Marionette DEBUG Closed connection 383
18:20:39 INFO - 1499304039632 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50380
18:20:39 INFO - 1499304039633 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:39 INFO - 1499304039634 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039637 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039649 Marionette TRACE 384 <- [1,1,null,{"sessionId":"810b7d40-febd-47f6-9a21-572c03c8ec0c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039651 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:20:39 INFO - 1499304039651 Marionette TRACE 384 <- [1,2,null,{}]
18:20:39 INFO - 1499304039652 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:20:39 INFO - 1499304039653 Marionette TRACE 384 <- [1,3,null,{}]
18:20:39 INFO - 1499304039654 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:20:39 INFO - 1499304039654 Marionette TRACE 384 <- [1,4,null,{}]
18:20:39 INFO - 1499304039656 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}]
18:20:39 INFO - TEST-START: test_click.py TestClick.test_input_file1499304039659 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:20:39 INFO - 1499304039660 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:20:39 INFO - 1499304039662 Marionette TRACE 384 <- [1,6,null,{}]
18:20:39 INFO - 1499304039664 Marionette DEBUG Closed connection 384
18:20:39 INFO - 1499304039665 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50381
18:20:39 INFO - 1499304039665 Marionette DEBUG Closed connection 385
18:20:39 INFO - 1499304039666 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50382
18:20:39 INFO - 1499304039667 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:39 INFO - 1499304039668 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039671 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039674 Marionette TRACE 386 <- [1,1,null,{"sessionId":"eac0017c-b379-4dd8-ad38-b4a5ae5643d0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039675 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:20:39 INFO - 1499304039677 Marionette TRACE 386 <- [1,2,null,{}]
18:20:39 INFO - 1499304039679 Marionette DEBUG Closed connection 386
18:20:39 INFO - 1499304039679 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50383
18:20:39 INFO - 1499304039680 Marionette DEBUG Closed connection 387
18:20:39 INFO - 1499304039681 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50384
18:20:39 INFO - 1499304039682 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:20:39 INFO - 1499304039683 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039686 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039689 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4e847501-5bd1-437f-acbc-f60a2cc96b22","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039690 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:20:39 INFO - 1499304039692 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"
18:20:39 INFO - 1499304039694 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"
18:20:39 INFO - 1499304039695 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"
18:20:39 INFO - 1499304039702 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:20:39 INFO - 1499304039707 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:20:39 INFO - 1499304039710 Marionette TRACE 388 <- [1,2,null,{}]
18:20:39 INFO - 1499304039725 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:20:39 INFO - 1499304039728 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f5567e9-0947-4262-bd1e-18ebb4f95582","ELEMENT":"3f5567e9-0947-4262-bd1e-18ebb4f95582"}}]
18:20:39 INFO - 1499304039730 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"3f5567e9-0947-4262-bd1e-18ebb4f95582"}]
18:20:39 INFO - 1499304039758 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]
18:20:39 INFO - 1499304039760 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}]
18:20:39 INFO - TEST-END: test_click.py TestClick.test_input_file1499304039763 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:20:39 INFO - 1499304039764 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:20:39 INFO - 1499304039766 Marionette TRACE 388 <- [1,6,null,{}]
18:20:39 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 137ms
18:20:39 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:20:39 INFO - 1499304039767 Marionette DEBUG Closed connection 388
18:20:39 INFO - 1499304039768 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50385
18:20:39 INFO - 1499304039769 Marionette DEBUG Closed connection 389
18:20:39 INFO - 1499304039770 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50386
18:20:39 INFO - 1499304039771 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:39 INFO - 1499304039772 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039775 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039778 Marionette TRACE 390 <- [1,1,null,{"sessionId":"8becef37-493f-43be-af5a-b4f5539e4e67","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039779 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:20:39 INFO - 1499304039780 Marionette TRACE 390 <- [1,2,null,{}]
18:20:39 INFO - 1499304039781 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:20:39 INFO - 1499304039781 Marionette TRACE 390 <- [1,3,null,{}]
18:20:39 INFO - 1499304039782 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:20:39 INFO - 1499304039783 Marionette TRACE 390 <- [1,4,null,{}]
18:20:39 INFO - 1499304039785 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}]
18:20:39 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499304039788 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:20:39 INFO - 1499304039789 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:20:39 INFO - 1499304039790 Marionette TRACE 390 <- [1,6,null,{}]
18:20:39 INFO - 1499304039792 Marionette DEBUG Closed connection 390
18:20:39 INFO - 1499304039793 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50387
18:20:39 INFO - 1499304039793 Marionette DEBUG Closed connection 391
18:20:39 INFO - 1499304039794 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50388
18:20:39 INFO - 1499304039795 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:39 INFO - 1499304039796 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039799 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039802 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fc509d18-5475-456c-8963-89f31cc87046","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039803 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:20:39 INFO - 1499304039805 Marionette TRACE 392 <- [1,2,null,{}]
18:20:39 INFO - 1499304039806 Marionette DEBUG Closed connection 392
18:20:39 INFO - 1499304039807 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50389
18:20:39 INFO - 1499304039808 Marionette DEBUG Closed connection 393
18:20:39 INFO - 1499304039809 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50390
18:20:39 INFO - 1499304039810 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:20:39 INFO - 1499304039810 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:39 INFO - 1499304039813 Marionette DEBUG Register listener.js for window 4294967297
18:20:39 INFO - 1499304039817 Marionette TRACE 394 <- [1,1,null,{"sessionId":"9d69d52c-74ff-4741-b9e3-173e9ee71c4b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:39 INFO - 1499304039818 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"}]
18:20:39 INFO - 1499304039820 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:20:39 INFO - 1499304039822 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:20:39 INFO - 1499304039823 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:20:39 INFO - 1499304039833 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"
18:20:39 INFO - 1499304039835 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"
18:20:39 INFO - 1499304039842 Marionette TRACE 394 <- [1,2,null,{}]
18:20:39 INFO - 1499304039846 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:20:39 INFO - 1499304039853 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f2fd5ff8-2e20-446a-983e-5b884e7f4cd0","ELEMENT":"f2fd5ff8-2e20-446a-983e-5b884e7f4cd0"}}]
18:20:39 INFO - 1499304039854 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:20:39 INFO - 1499304039856 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"311f6d08-5f9a-4837-8e80-c1f4813752cb","ELEMENT":"311f6d08-5f9a-4837-8e80-c1f4813752cb"}}]
18:20:39 INFO - 1499304039857 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"f2fd5ff8-2e20-446a-983e-5b884e7f4cd0"}]
18:20:40 INFO - 1499304040098 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:20:40 INFO - 1499304040099 Marionette TRACE 394 <- [1,5,null,{}]
18:20:40 INFO - 1499304040100 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"311f6d08-5f9a-4837-8e80-c1f4813752cb"}]
18:20:40 INFO - 1499304040119 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,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]
18:20:40 INFO - 1499304040121 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:20:40 INFO - 1499304040124 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:20:40 INFO - 1499304040125 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}]
18:20:40 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499304040128 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:20:40 INFO - 1499304040129 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:20:40 INFO - 1499304040131 Marionette TRACE 394 <- [1,9,null,{}]
18:20:40 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 365ms
18:20:40 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:20:40 INFO - 1499304040133 Marionette DEBUG Closed connection 394
18:20:40 INFO - 1499304040134 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50391
18:20:40 INFO - 1499304040134 Marionette DEBUG Closed connection 395
18:20:40 INFO - 1499304040135 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50392
18:20:40 INFO - 1499304040136 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:40 INFO - 1499304040137 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:40 INFO - 1499304040140 Marionette DEBUG Register listener.js for window 4294967297
18:20:40 INFO - 1499304040143 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8f855d38-6db0-4e51-bad6-45159b694f9f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:40 INFO - 1499304040144 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:20:40 INFO - 1499304040145 Marionette TRACE 396 <- [1,2,null,{}]
18:20:40 INFO - 1499304040146 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:20:40 INFO - 1499304040146 Marionette TRACE 396 <- [1,3,null,{}]
18:20:40 INFO - 1499304040147 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:20:40 INFO - 1499304040148 Marionette TRACE 396 <- [1,4,null,{}]
18:20:40 INFO - 1499304040150 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}]
18:20:40 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499304040153 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:20:40 INFO - 1499304040154 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:20:40 INFO - 1499304040155 Marionette TRACE 396 <- [1,6,null,{}]
18:20:40 INFO - 1499304040157 Marionette DEBUG Closed connection 396
18:20:40 INFO - 1499304040158 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50393
18:20:40 INFO - 1499304040159 Marionette DEBUG Closed connection 397
18:20:40 INFO - 1499304040160 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50394
18:20:40 INFO - 1499304040161 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:20:40 INFO - 1499304040162 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:40 INFO - 1499304040165 Marionette DEBUG Register listener.js for window 4294967297
18:20:40 INFO - 1499304040168 Marionette TRACE 398 <- [1,1,null,{"sessionId":"7cb1d26f-4d2b-4c2e-81d5-7b13479474eb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:40 INFO - 1499304040169 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:20:40 INFO - 1499304040171 Marionette TRACE 398 <- [1,2,null,{}]
18:20:40 INFO - 1499304040173 Marionette DEBUG Closed connection 398
18:20:40 INFO - 1499304040173 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50395
18:20:40 INFO - 1499304040174 Marionette DEBUG Closed connection 399
18:20:40 INFO - 1499304040175 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50396
18:20:40 INFO - 1499304040176 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:20:40 INFO - 1499304040177 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}
18:20:40 INFO - 1499304040180 Marionette DEBUG Register listener.js for window 4294967297
18:20:40 INFO - 1499304040183 Marionette TRACE 400 <- [1,1,null,{"sessionId":"0157c864-20a9-4c13-afdb-2d12c0a4285a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":768,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqag1rc.mozrunner","moz:accessibilityChecks":false}}]
18:20:40 INFO - 1499304040184 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"}]
18:20:40 INFO - 1499304040186 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"
18:20:40 INFO - 1499304040189 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"
18:20:40 INFO - 1499304040189 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"
18:20:40 INFO - 1499304040198 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"
18:20:40 INFO - 1499304040201 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"
18:20:40 INFO - 1499304040209 Marionette TRACE 400 <- [1,2,null,{}]
18:20:40 INFO - 1499304040212 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:20:40 INFO - 1499304040220 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf02ba99-2c32-4cdc-a1dd-1637c84fbc26","ELEMENT":"bf02ba99-2c32-4cdc-a1dd-1637c84fbc26"}}]
18:20:40 INFO - 1499304040221 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"bf02ba99-2c32-4cdc-a1dd-1637c84fbc26"}]
18:20:40 INFO - 1499304040251 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:20:40 INFO - 1499304040252 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"bf02ba99-2c32-4cdc-a1dd-1637c84fbc26"}]
18:20:40 INFO - 1499304040271 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element