could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
12:47:47 INFO - 1499543267090 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}]
12:47:47 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499543267093 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499543267094 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499543267095 Marionette TRACE 352 <- [1,6,null,{}]
12:47:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 120ms
12:47:47 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:47:47 INFO - 1499543267096 Marionette DEBUG Closed connection 352
12:47:47 INFO - 1499543267098 Marionette DEBUG Accepted connection 353 from 127.0.0.1:51188
12:47:47 INFO - 1499543267100 Marionette DEBUG Closed connection 353
12:47:47 INFO - 1499543267101 Marionette DEBUG Accepted connection 354 from 127.0.0.1:51189
12:47:47 INFO - 1499543267102 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499543267102 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267104 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267108 Marionette TRACE 354 <- [1,1,null,{"sessionId":"5126f362-f3fa-475e-878d-67ee61ab5524","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267109 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:47:47 INFO - 1499543267109 Marionette TRACE 354 <- [1,2,null,{}]
12:47:47 INFO - 1499543267110 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:47 INFO - 1499543267111 Marionette TRACE 354 <- [1,3,null,{}]
12:47:47 INFO - 1499543267112 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:47 INFO - 1499543267112 Marionette TRACE 354 <- [1,4,null,{}]
12:47:47 INFO - 1499543267114 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}]
12:47:47 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499543267116 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499543267117 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499543267118 Marionette TRACE 354 <- [1,6,null,{}]
12:47:47 INFO - 1499543267120 Marionette DEBUG Closed connection 354
12:47:47 INFO - 1499543267120 Marionette DEBUG Accepted connection 355 from 127.0.0.1:51190
12:47:47 INFO - 1499543267121 Marionette DEBUG Closed connection 355
12:47:47 INFO - 1499543267122 Marionette DEBUG Accepted connection 356 from 127.0.0.1:51191
12:47:47 INFO - 1499543267123 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499543267123 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267125 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267128 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d85113c9-d060-4e7b-8ef7-8b03011e8cee","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267129 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:47:47 INFO - 1499543267130 Marionette TRACE 356 <- [1,2,null,{}]
12:47:47 INFO - 1499543267132 Marionette DEBUG Closed connection 356
12:47:47 INFO - 1499543267133 Marionette DEBUG Accepted connection 357 from 127.0.0.1:51192
12:47:47 INFO - 1499543267133 Marionette DEBUG Closed connection 357
12:47:47 INFO - 1499543267134 Marionette DEBUG Accepted connection 358 from 127.0.0.1:51193
12:47:47 INFO - 1499543267135 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:47 INFO - 1499543267136 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267138 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267142 Marionette TRACE 358 <- [1,1,null,{"sessionId":"ea180bdd-0551-4801-b456-7b967ac180fd","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267143 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:50747/clicks.html"}]
12:47:47 INFO - 1499543267145 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50747/hidden.html"
12:47:47 INFO - 1499543267152 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50747/hidden.html"
12:47:47 INFO - 1499543267153 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50747/hidden.html"
12:47:47 INFO - 1499543267159 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50747/clicks.html"
12:47:47 INFO - 1499543267165 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50747/clicks.html"
12:47:47 INFO - 1499543267168 Marionette TRACE 358 <- [1,2,null,{}]
12:47:47 INFO - 1499543267174 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:47:47 INFO - 1499543267177 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"aa6c8714-62c0-4555-9d8a-99c8c56b44fc","ELEMENT":"aa6c8714-62c0-4555-9d8a-99c8c56b44fc"}}]
12:47:47 INFO - 1499543267178 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"aa6c8714-62c0-4555-9d8a-99c8c56b44fc"}]
12:47:47 INFO - 1499543267225 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50747/clicks.html"
12:47:47 INFO - 1499543267236 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50747/clicks.html"
12:47:47 INFO - 1499543267236 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50747/clicks.html"
12:47:47 INFO - 1499543267243 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50747/test.html"
12:47:47 INFO - 1499543267246 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50747/test.html"
12:47:47 INFO - 1499543267257 Marionette TRACE 358 <- [1,4,null,{}]
12:47:47 INFO - 1499543267269 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:47:47 INFO - 1499543267273 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"938f61a3-8df7-4446-9a12-01b777603ab3","ELEMENT":"938f61a3-8df7-4446-9a12-01b777603ab3"}}]
12:47:47 INFO - 1499543267274 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:47:47 INFO - 1499543267275 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:47:47 INFO - 1499543267276 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}]
12:47:47 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499543267279 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:47:47 INFO - 1499543267280 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:47:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 185ms
12:47:47 INFO - TEST-START | test_click.py TestClick.test_container_element
12:47:47 INFO - 1499543267281 Marionette TRACE 358 <- [1,8,null,{}]
12:47:47 INFO - 1499543267283 Marionette DEBUG Closed connection 358
12:47:47 INFO - 1499543267284 Marionette DEBUG Accepted connection 359 from 127.0.0.1:51196
12:47:47 INFO - 1499543267285 Marionette DEBUG Closed connection 359
12:47:47 INFO - 1499543267286 Marionette DEBUG Accepted connection 360 from 127.0.0.1:51197
12:47:47 INFO - 1499543267287 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499543267287 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267290 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267293 Marionette TRACE 360 <- [1,1,null,{"sessionId":"00d652da-2c1b-4ddf-b7b6-650d8c28e4f4","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267294 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:47:47 INFO - 1499543267295 Marionette TRACE 360 <- [1,2,null,{}]
12:47:47 INFO - 1499543267296 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:47 INFO - 1499543267296 Marionette TRACE 360 <- [1,3,null,{}]
12:47:47 INFO - 1499543267297 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:47 INFO - 1499543267298 Marionette TRACE 360 <- [1,4,null,{}]
12:47:47 INFO - 1499543267300 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}]
12:47:47 INFO - TEST-START: test_click.py TestClick.test_container_element1499543267302 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499543267303 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499543267305 Marionette TRACE 360 <- [1,6,null,{}]
12:47:47 INFO - 1499543267306 Marionette DEBUG Closed connection 360
12:47:47 INFO - 1499543267307 Marionette DEBUG Accepted connection 361 from 127.0.0.1:51198
12:47:47 INFO - 1499543267307 Marionette DEBUG Closed connection 361
12:47:47 INFO - 1499543267308 Marionette DEBUG Accepted connection 362 from 127.0.0.1:51199
12:47:47 INFO - 1499543267309 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499543267310 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267312 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267315 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e5859686-0d98-476d-af90-67384583c306","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267317 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:47:47 INFO - 1499543267318 Marionette TRACE 362 <- [1,2,null,{}]
12:47:47 INFO - 1499543267319 Marionette DEBUG Closed connection 362
12:47:47 INFO - 1499543267320 Marionette DEBUG Accepted connection 363 from 127.0.0.1:51200
12:47:47 INFO - 1499543267321 Marionette DEBUG Closed connection 363
12:47:47 INFO - 1499543267321 Marionette DEBUG Accepted connection 364 from 127.0.0.1:51201
12:47:47 INFO - 1499543267322 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:47 INFO - 1499543267323 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267325 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267329 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0cb9541a-c0d9-4a35-88e1-a28a414b2015","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267330 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"}]
12:47:47 INFO - 1499543267332 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50747/test.html"
12:47:47 INFO - 1499543267334 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50747/test.html"
12:47:47 INFO - 1499543267334 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50747/test.html"
12:47:47 INFO - 1499543267340 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"
12:47:47 INFO - 1499543267343 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"
12:47:47 INFO - 1499543267354 Marionette TRACE 364 <- [1,2,null,{}]
12:47:47 INFO - 1499543267359 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:47:47 INFO - 1499543267365 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6d7b1fcc-9333-4979-8d4c-dd5a98dcd915","ELEMENT":"6d7b1fcc-9333-4979-8d4c-dd5a98dcd915"}}]
12:47:47 INFO - 1499543267367 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6d7b1fcc-9333-4979-8d4c-dd5a98dcd915"}]
12:47:47 INFO - 1499543267605 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:47 INFO - 1499543267606 Marionette TRACE 364 <- [1,4,null,{}]
12:47:47 INFO - 1499543267607 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6d7b1fcc-9333-4979-8d4c-dd5a98dcd915","name":"selected"}]
12:47:47 INFO - 1499543267611 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:47:47 INFO - 1499543267613 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}]
12:47:47 INFO - TEST-END: test_click.py TestClick.test_container_element1499543267616 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:47:47 INFO - 1499543267617 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:47:47 INFO - 1499543267618 Marionette TRACE 364 <- [1,7,null,{}]
12:47:47 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 336ms
12:47:47 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:47:47 INFO - 1499543267620 Marionette DEBUG Closed connection 364
12:47:47 INFO - 1499543267621 Marionette DEBUG Accepted connection 365 from 127.0.0.1:51202
12:47:47 INFO - 1499543267621 Marionette DEBUG Closed connection 365
12:47:47 INFO - 1499543267622 Marionette DEBUG Accepted connection 366 from 127.0.0.1:51203
12:47:47 INFO - 1499543267623 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499543267624 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267626 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267629 Marionette TRACE 366 <- [1,1,null,{"sessionId":"06ea0def-9b51-447f-ad0f-c528bd06775f","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267630 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:47:47 INFO - 1499543267631 Marionette TRACE 366 <- [1,2,null,{}]
12:47:47 INFO - 1499543267631 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:47 INFO - 1499543267632 Marionette TRACE 366 <- [1,3,null,{}]
12:47:47 INFO - 1499543267633 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:47 INFO - 1499543267633 Marionette TRACE 366 <- [1,4,null,{}]
12:47:47 INFO - 1499543267635 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}]
12:47:47 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499543267638 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499543267639 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499543267640 Marionette TRACE 366 <- [1,6,null,{}]
12:47:47 INFO - 1499543267641 Marionette DEBUG Closed connection 366
12:47:47 INFO - 1499543267641 Marionette DEBUG Accepted connection 367 from 127.0.0.1:51204
12:47:47 INFO - 1499543267642 Marionette DEBUG Closed connection 367
12:47:47 INFO - 1499543267643 Marionette DEBUG Accepted connection 368 from 127.0.0.1:51205
12:47:47 INFO - 1499543267644 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499543267644 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267646 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267649 Marionette TRACE 368 <- [1,1,null,{"sessionId":"49991398-024b-4adb-bf57-75ccf83b6382","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267650 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:47:47 INFO - 1499543267651 Marionette TRACE 368 <- [1,2,null,{}]
12:47:47 INFO - 1499543267653 Marionette DEBUG Closed connection 368
12:47:47 INFO - 1499543267653 Marionette DEBUG Accepted connection 369 from 127.0.0.1:51206
12:47:47 INFO - 1499543267654 Marionette DEBUG Closed connection 369
12:47:47 INFO - 1499543267655 Marionette DEBUG Accepted connection 370 from 127.0.0.1:51207
12:47:47 INFO - 1499543267656 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:47 INFO - 1499543267656 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267658 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267661 Marionette TRACE 370 <- [1,1,null,{"sessionId":"d62edf69-40cd-43c5-9627-1df26b057f76","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267662 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"}]
12:47:47 INFO - 1499543267664 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"
12:47:47 INFO - 1499543267667 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"
12:47:47 INFO - 1499543267667 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"
12:47:47 INFO - 1499543267674 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"
12:47:47 INFO - 1499543267679 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"
12:47:47 INFO - 1499543267684 Marionette TRACE 370 <- [1,2,null,{}]
12:47:47 INFO - 1499543267687 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:47:47 INFO - 1499543267691 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ca9b4031-b3bc-4756-b537-f3fb5c238c6a","ELEMENT":"ca9b4031-b3bc-4756-b537-f3fb5c238c6a"}}]
12:47:47 INFO - 1499543267692 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ca9b4031-b3bc-4756-b537-f3fb5c238c6a"}]
12:47:47 INFO - 1499543267930 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:47 INFO - 1499543267931 Marionette TRACE 370 <- [1,4,null,{}]
12:47:47 INFO - 1499543267933 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ca9b4031-b3bc-4756-b537-f3fb5c238c6a","name":"selected"}]
12:47:47 INFO - 1499543267937 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:47:47 INFO - 1499543267939 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}]
12:47:47 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499543267941 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:47:47 INFO - 1499543267942 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:47:47 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 325ms
12:47:47 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:47:47 INFO - 1499543267943 Marionette TRACE 370 <- [1,7,null,{}]
12:47:47 INFO - 1499543267945 Marionette DEBUG Closed connection 370
12:47:47 INFO - 1499543267946 Marionette DEBUG Accepted connection 371 from 127.0.0.1:51208
12:47:47 INFO - 1499543267947 Marionette DEBUG Closed connection 371
12:47:47 INFO - 1499543267948 Marionette DEBUG Accepted connection 372 from 127.0.0.1:51209
12:47:47 INFO - 1499543267949 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499543267949 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267951 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267954 Marionette TRACE 372 <- [1,1,null,{"sessionId":"0368a48a-00a4-4517-b79b-aafe1b202094","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267955 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:47:47 INFO - 1499543267956 Marionette TRACE 372 <- [1,2,null,{}]
12:47:47 INFO - 1499543267957 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:47 INFO - 1499543267957 Marionette TRACE 372 <- [1,3,null,{}]
12:47:47 INFO - 1499543267958 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:47 INFO - 1499543267958 Marionette TRACE 372 <- [1,4,null,{}]
12:47:47 INFO - 1499543267960 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}]
12:47:47 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499543267963 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:47:47 INFO - 1499543267964 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:47:47 INFO - 1499543267965 Marionette TRACE 372 <- [1,6,null,{}]
12:47:47 INFO - 1499543267966 Marionette DEBUG Closed connection 372
12:47:47 INFO - 1499543267967 Marionette DEBUG Accepted connection 373 from 127.0.0.1:51210
12:47:47 INFO - 1499543267967 Marionette DEBUG Closed connection 373
12:47:47 INFO - 1499543267968 Marionette DEBUG Accepted connection 374 from 127.0.0.1:51211
12:47:47 INFO - 1499543267969 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:47 INFO - 1499543267970 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267972 Marionette DEBUG Register listener.js for window 4294967297
12:47:47 INFO - 1499543267975 Marionette TRACE 374 <- [1,1,null,{"sessionId":"00ee8225-8837-498d-ae43-4fe9ad4a479d","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:47 INFO - 1499543267976 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:47:47 INFO - 1499543267977 Marionette TRACE 374 <- [1,2,null,{}]
12:47:47 INFO - 1499543267978 Marionette DEBUG Closed connection 374
12:47:47 INFO - 1499543267979 Marionette DEBUG Accepted connection 375 from 127.0.0.1:51212
12:47:47 INFO - 1499543267980 Marionette DEBUG Closed connection 375
12:47:47 INFO - 1499543267980 Marionette DEBUG Accepted connection 376 from 127.0.0.1:51213
12:47:47 INFO - 1499543267981 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:47 INFO - 1499543267982 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:47 INFO - 1499543267984 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543267995 Marionette TRACE 376 <- [1,1,null,{"sessionId":"59e95744-548a-455a-b5a8-cfd815b9d2e7","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543267996 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"}]
12:47:48 INFO - 1499543268000 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"
12:47:48 INFO - 1499543268002 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"
12:47:48 INFO - 1499543268002 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"
12:47:48 INFO - 1499543268008 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"
12:47:48 INFO - 1499543268011 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"
12:47:48 INFO - 1499543268019 Marionette TRACE 376 <- [1,2,null,{}]
12:47:48 INFO - 1499543268023 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:47:48 INFO - 1499543268026 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"70a4e21b-92c5-4bb1-84e2-b7b48e07ab38","ELEMENT":"70a4e21b-92c5-4bb1-84e2-b7b48e07ab38"}}]
12:47:48 INFO - 1499543268026 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"70a4e21b-92c5-4bb1-84e2-b7b48e07ab38"}]
12:47:48 INFO - 1499543268265 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:48 INFO - 1499543268266 Marionette TRACE 376 <- [1,4,null,{}]
12:47:48 INFO - 1499543268268 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}]
12:47:48 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499543268271 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:47:48 INFO - 1499543268272 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:47:48 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 329ms
12:47:48 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:47:48 INFO - 1499543268273 Marionette TRACE 376 <- [1,6,null,{}]
12:47:48 INFO - 1499543268275 Marionette DEBUG Closed connection 376
12:47:48 INFO - 1499543268276 Marionette DEBUG Accepted connection 377 from 127.0.0.1:51214
12:47:48 INFO - 1499543268277 Marionette DEBUG Closed connection 377
12:47:48 INFO - 1499543268278 Marionette DEBUG Accepted connection 378 from 127.0.0.1:51215
12:47:48 INFO - 1499543268279 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:48 INFO - 1499543268279 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268281 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268284 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9c6d5812-94de-485f-936e-19a2de24ac80","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268285 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:47:48 INFO - 1499543268286 Marionette TRACE 378 <- [1,2,null,{}]
12:47:48 INFO - 1499543268286 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:48 INFO - 1499543268287 Marionette TRACE 378 <- [1,3,null,{}]
12:47:48 INFO - 1499543268288 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:48 INFO - 1499543268288 Marionette TRACE 378 <- [1,4,null,{}]
12:47:48 INFO - 1499543268290 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}]
12:47:48 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499543268292 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:47:48 INFO - 1499543268293 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:47:48 INFO - 1499543268294 Marionette TRACE 378 <- [1,6,null,{}]
12:47:48 INFO - 1499543268296 Marionette DEBUG Closed connection 378
12:47:48 INFO - 1499543268296 Marionette DEBUG Accepted connection 379 from 127.0.0.1:51216
12:47:48 INFO - 1499543268297 Marionette DEBUG Closed connection 379
12:47:48 INFO - 1499543268298 Marionette DEBUG Accepted connection 380 from 127.0.0.1:51217
12:47:48 INFO - 1499543268299 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:48 INFO - 1499543268299 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268301 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268304 Marionette TRACE 380 <- [1,1,null,{"sessionId":"941c81f1-1aaf-4d86-93de-3e0d61d6b0b0","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268305 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:47:48 INFO - 1499543268306 Marionette TRACE 380 <- [1,2,null,{}]
12:47:48 INFO - 1499543268307 Marionette DEBUG Closed connection 380
12:47:48 INFO - 1499543268308 Marionette DEBUG Accepted connection 381 from 127.0.0.1:51218
12:47:48 INFO - 1499543268308 Marionette DEBUG Closed connection 381
12:47:48 INFO - 1499543268309 Marionette DEBUG Accepted connection 382 from 127.0.0.1:51219
12:47:48 INFO - 1499543268310 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:48 INFO - 1499543268311 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268313 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268315 Marionette TRACE 382 <- [1,1,null,{"sessionId":"a697dc61-8383-408f-b19a-d77c24e62fc1","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268317 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"}]
12:47:48 INFO - 1499543268318 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"
12:47:48 INFO - 1499543268320 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"
12:47:48 INFO - 1499543268321 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"
12:47:48 INFO - 1499543268327 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"
12:47:48 INFO - 1499543268330 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"
12:47:48 INFO - 1499543268336 Marionette TRACE 382 <- [1,2,null,{}]
12:47:48 INFO - 1499543268340 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:47:48 INFO - 1499543268343 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a5d0b2d-af53-4554-86db-87be989e3c6b","ELEMENT":"7a5d0b2d-af53-4554-86db-87be989e3c6b"}}]
12:47:48 INFO - 1499543268349 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"7a5d0b2d-af53-4554-86db-87be989e3c6b"}]
12:47:48 INFO - 1499543268592 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:48 INFO - 1499543268593 Marionette TRACE 382 <- [1,4,null,{}]
12:47:48 INFO - 1499543268595 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}]
12:47:48 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499543268598 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:47:48 INFO - 1499543268599 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:47:48 INFO - 1499543268600 Marionette TRACE 382 <- [1,6,null,{}]
12:47:48 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 326ms
12:47:48 INFO - TEST-START | test_click.py TestClick.test_input_file
12:47:48 INFO - 1499543268601 Marionette DEBUG Closed connection 382
12:47:48 INFO - 1499543268603 Marionette DEBUG Accepted connection 383 from 127.0.0.1:51220
12:47:48 INFO - 1499543268604 Marionette DEBUG Closed connection 383
12:47:48 INFO - 1499543268604 Marionette DEBUG Accepted connection 384 from 127.0.0.1:51221
12:47:48 INFO - 1499543268605 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:48 INFO - 1499543268606 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268608 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268611 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e6bb20d3-e89e-415d-bf8b-15f396ffb4d0","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268612 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:47:48 INFO - 1499543268613 Marionette TRACE 384 <- [1,2,null,{}]
12:47:48 INFO - 1499543268614 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:48 INFO - 1499543268614 Marionette TRACE 384 <- [1,3,null,{}]
12:47:48 INFO - 1499543268615 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:48 INFO - 1499543268615 Marionette TRACE 384 <- [1,4,null,{}]
12:47:48 INFO - 1499543268617 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}]
12:47:48 INFO - TEST-START: test_click.py TestClick.test_input_file1499543268620 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:47:48 INFO - 1499543268620 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:47:48 INFO - 1499543268622 Marionette TRACE 384 <- [1,6,null,{}]
12:47:48 INFO - 1499543268623 Marionette DEBUG Closed connection 384
12:47:48 INFO - 1499543268624 Marionette DEBUG Accepted connection 385 from 127.0.0.1:51222
12:47:48 INFO - 1499543268624 Marionette DEBUG Closed connection 385
12:47:48 INFO - 1499543268625 Marionette DEBUG Accepted connection 386 from 127.0.0.1:51223
12:47:48 INFO - 1499543268626 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:48 INFO - 1499543268627 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268629 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268632 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6c65b693-2c12-49a0-9102-387d7ad55d3b","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268633 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:47:48 INFO - 1499543268634 Marionette TRACE 386 <- [1,2,null,{}]
12:47:48 INFO - 1499543268635 Marionette DEBUG Closed connection 386
12:47:48 INFO - 1499543268636 Marionette DEBUG Accepted connection 387 from 127.0.0.1:51224
12:47:48 INFO - 1499543268637 Marionette DEBUG Closed connection 387
12:47:48 INFO - 1499543268637 Marionette DEBUG Accepted connection 388 from 127.0.0.1:51225
12:47:48 INFO - 1499543268638 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:48 INFO - 1499543268639 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268641 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268644 Marionette TRACE 388 <- [1,1,null,{"sessionId":"087186e9-8614-45a4-9f5a-3c3fd73f761a","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268645 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:47:48 INFO - 1499543268646 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"
12:47:48 INFO - 1499543268648 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"
12:47:48 INFO - 1499543268649 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"
12:47:48 INFO - 1499543268654 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:48 INFO - 1499543268658 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:48 INFO - 1499543268659 Marionette TRACE 388 <- [1,2,null,{}]
12:47:48 INFO - 1499543268672 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:47:48 INFO - 1499543268675 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"beb275c1-06b8-4f58-b683-d9fe260098d5","ELEMENT":"beb275c1-06b8-4f58-b683-d9fe260098d5"}}]
12:47:48 INFO - 1499543268676 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"beb275c1-06b8-4f58-b683-d9fe260098d5"}]
12:47:48 INFO - 1499543268699 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
12:47:48 INFO - 1499543268701 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}]
12:47:48 INFO - TEST-END: test_click.py TestClick.test_input_file1499543268704 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:47:48 INFO - 1499543268705 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:47:48 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 106ms
12:47:48 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:47:48 INFO - 1499543268707 Marionette TRACE 388 <- [1,6,null,{}]
12:47:48 INFO - 1499543268708 Marionette DEBUG Closed connection 388
12:47:48 INFO - 1499543268709 Marionette DEBUG Accepted connection 389 from 127.0.0.1:51226
12:47:48 INFO - 1499543268710 Marionette DEBUG Closed connection 389
12:47:48 INFO - 1499543268711 Marionette DEBUG Accepted connection 390 from 127.0.0.1:51227
12:47:48 INFO - 1499543268712 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:48 INFO - 1499543268712 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268715 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268718 Marionette TRACE 390 <- [1,1,null,{"sessionId":"bcd707af-1bf2-4fa9-8b90-2b38d35b41a9","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268719 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:47:48 INFO - 1499543268720 Marionette TRACE 390 <- [1,2,null,{}]
12:47:48 INFO - 1499543268720 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:48 INFO - 1499543268721 Marionette TRACE 390 <- [1,3,null,{}]
12:47:48 INFO - 1499543268722 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:48 INFO - 1499543268722 Marionette TRACE 390 <- [1,4,null,{}]
12:47:48 INFO - 1499543268724 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}]
12:47:48 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499543268727 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:47:48 INFO - 1499543268728 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:47:48 INFO - 1499543268729 Marionette TRACE 390 <- [1,6,null,{}]
12:47:48 INFO - 1499543268731 Marionette DEBUG Closed connection 390
12:47:48 INFO - 1499543268731 Marionette DEBUG Accepted connection 391 from 127.0.0.1:51228
12:47:48 INFO - 1499543268732 Marionette DEBUG Closed connection 391
12:47:48 INFO - 1499543268733 Marionette DEBUG Accepted connection 392 from 127.0.0.1:51229
12:47:48 INFO - 1499543268734 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:48 INFO - 1499543268735 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268737 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268740 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ac3688ee-78e3-4f32-b9d6-7ab87027791e","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268741 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:47:48 INFO - 1499543268742 Marionette TRACE 392 <- [1,2,null,{}]
12:47:48 INFO - 1499543268744 Marionette DEBUG Closed connection 392
12:47:48 INFO - 1499543268745 Marionette DEBUG Accepted connection 393 from 127.0.0.1:51230
12:47:48 INFO - 1499543268745 Marionette DEBUG Closed connection 393
12:47:48 INFO - 1499543268746 Marionette DEBUG Accepted connection 394 from 127.0.0.1:51231
12:47:48 INFO - 1499543268747 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:48 INFO - 1499543268748 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:48 INFO - 1499543268750 Marionette DEBUG Register listener.js for window 4294967297
12:47:48 INFO - 1499543268753 Marionette TRACE 394 <- [1,1,null,{"sessionId":"ceca2879-05df-48b4-af11-9541a4baba42","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:48 INFO - 1499543268754 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"}]
12:47:48 INFO - 1499543268756 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:48 INFO - 1499543268758 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:48 INFO - 1499543268758 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:47:48 INFO - 1499543268766 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"
12:47:48 INFO - 1499543268768 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"
12:47:48 INFO - 1499543268771 Marionette TRACE 394 <- [1,2,null,{}]
12:47:48 INFO - 1499543268775 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:47:48 INFO - 1499543268782 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"519ed1f8-fc0c-4a68-a206-81068dffef17","ELEMENT":"519ed1f8-fc0c-4a68-a206-81068dffef17"}}]
12:47:48 INFO - 1499543268784 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:47:48 INFO - 1499543268786 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a0cbb66f-a53a-4e8b-85c5-235ee91fccbe","ELEMENT":"a0cbb66f-a53a-4e8b-85c5-235ee91fccbe"}}]
12:47:48 INFO - 1499543268787 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"519ed1f8-fc0c-4a68-a206-81068dffef17"}]
12:47:49 INFO - 1499543269025 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:47:49 INFO - 1499543269026 Marionette TRACE 394 <- [1,5,null,{}]
12:47:49 INFO - 1499543269027 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a0cbb66f-a53a-4e8b-85c5-235ee91fccbe"}]
12:47:49 INFO - 1499543269041 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
12:47:49 INFO - 1499543269042 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:47:49 INFO - 1499543269045 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:47:49 INFO - 1499543269047 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}]
12:47:49 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499543269049 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:47:49 INFO - 1499543269050 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:47:49 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 344ms
12:47:49 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:47:49 INFO - 1499543269051 Marionette TRACE 394 <- [1,9,null,{}]
12:47:49 INFO - 1499543269053 Marionette DEBUG Closed connection 394
12:47:49 INFO - 1499543269054 Marionette DEBUG Accepted connection 395 from 127.0.0.1:51232
12:47:49 INFO - 1499543269055 Marionette DEBUG Closed connection 395
12:47:49 INFO - 1499543269055 Marionette DEBUG Accepted connection 396 from 127.0.0.1:51233
12:47:49 INFO - 1499543269056 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:49 INFO - 1499543269057 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:49 INFO - 1499543269059 Marionette DEBUG Register listener.js for window 4294967297
12:47:49 INFO - 1499543269062 Marionette TRACE 396 <- [1,1,null,{"sessionId":"06f069db-4169-4de0-bce0-fa357aa593de","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:49 INFO - 1499543269063 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:47:49 INFO - 1499543269064 Marionette TRACE 396 <- [1,2,null,{}]
12:47:49 INFO - 1499543269064 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:47:49 INFO - 1499543269065 Marionette TRACE 396 <- [1,3,null,{}]
12:47:49 INFO - 1499543269066 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:47:49 INFO - 1499543269066 Marionette TRACE 396 <- [1,4,null,{}]
12:47:49 INFO - 1499543269068 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}]
12:47:49 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499543269070 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:47:49 INFO - 1499543269071 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:47:49 INFO - 1499543269072 Marionette TRACE 396 <- [1,6,null,{}]
12:47:49 INFO - 1499543269074 Marionette DEBUG Closed connection 396
12:47:49 INFO - 1499543269074 Marionette DEBUG Accepted connection 397 from 127.0.0.1:51234
12:47:49 INFO - 1499543269075 Marionette DEBUG Closed connection 397
12:47:49 INFO - 1499543269076 Marionette DEBUG Accepted connection 398 from 127.0.0.1:51235
12:47:49 INFO - 1499543269076 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:47:49 INFO - 1499543269077 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:49 INFO - 1499543269079 Marionette DEBUG Register listener.js for window 4294967297
12:47:49 INFO - 1499543269082 Marionette TRACE 398 <- [1,1,null,{"sessionId":"ecc6d10b-267e-4249-8d9a-6c1731249e6c","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:49 INFO - 1499543269083 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:47:49 INFO - 1499543269084 Marionette TRACE 398 <- [1,2,null,{}]
12:47:49 INFO - 1499543269085 Marionette DEBUG Closed connection 398
12:47:49 INFO - 1499543269086 Marionette DEBUG Accepted connection 399 from 127.0.0.1:51236
12:47:49 INFO - 1499543269086 Marionette DEBUG Closed connection 399
12:47:49 INFO - 1499543269087 Marionette DEBUG Accepted connection 400 from 127.0.0.1:51237
12:47:49 INFO - 1499543269088 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:47:49 INFO - 1499543269089 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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}
12:47:49 INFO - 1499543269091 Marionette DEBUG Register listener.js for window 4294967297
12:47:49 INFO - 1499543269094 Marionette TRACE 400 <- [1,1,null,{"sessionId":"777ce1bf-20ca-4d6e-aa75-49f975eca27a","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":1624,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1os9ud.mozrunner","moz:accessibilityChecks":false}}]
12:47:49 INFO - 1499543269096 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"}]
12:47:49 INFO - 1499543269098 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"
12:47:49 INFO - 1499543269100 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"
12:47:49 INFO - 1499543269100 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"
12:47:49 INFO - 1499543269106 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"
12:47:49 INFO - 1499543269108 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"
12:47:49 INFO - 1499543269111 Marionette TRACE 400 <- [1,2,null,{}]
12:47:49 INFO - 1499543269119 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:47:49 INFO - 1499543269121 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"af7c0790-e07a-4d7c-8e8f-f1236721e13c","ELEMENT":"af7c0790-e07a-4d7c-8e8f-f1236721e13c"}}]
12:47:49 INFO - 1499543269122 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"af7c0790-e07a-4d7c-8e8f-f1236721e13c"}]
12:47:49 INFO - 1499543269143 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:47:49 INFO - 1499543269144 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"af7c0790-e07a-4d7c-8e8f-f1236721e13c"}]
12:47:49 INFO - 1499543269159 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element