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:1330:5\n"},null]
06:32:25 INFO - 1498829545227 Marionette TRACE 358 -> [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}]
06:32:25 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498829545230 Marionette TRACE 358 <- [1,5,null,{"value":null}]
06:32:25 INFO - 1498829545235 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
06:32:25 INFO - 1498829545237 Marionette TRACE 358 <- [1,6,null,{}]
06:32:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 151ms
06:32:25 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:32:25 INFO - 1498829545239 Marionette DEBUG Closed connection 358
06:32:25 INFO - 1498829545240 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49957
06:32:25 INFO - 1498829545241 Marionette DEBUG Closed connection 359
06:32:25 INFO - 1498829545242 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49958
06:32:25 INFO - 1498829545243 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:25 INFO - 1498829545243 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545246 Marionette DEBUG Register listener.js for window 7
06:32:25 INFO - 1498829545249 Marionette TRACE 360 <- [1,1,null,{"sessionId":"1c893f6c-0819-4fa2-ac10-858efe24407d","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:25 INFO - 1498829545250 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:32:25 INFO - 1498829545251 Marionette TRACE 360 <- [1,2,null,{}]
06:32:25 INFO - 1498829545252 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:32:25 INFO - 1498829545252 Marionette TRACE 360 <- [1,3,null,{}]
06:32:25 INFO - 1498829545253 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:32:25 INFO - 1498829545253 Marionette TRACE 360 <- [1,4,null,{}]
06:32:25 INFO - 1498829545255 Marionette TRACE 360 -> [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}]
06:32:25 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498829545258 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:32:25 INFO - 1498829545259 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:32:25 INFO - 1498829545260 Marionette TRACE 360 <- [1,6,null,{}]
06:32:25 INFO - 1498829545262 Marionette DEBUG Closed connection 360
06:32:25 INFO - 1498829545263 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49959
06:32:25 INFO - 1498829545264 Marionette DEBUG Closed connection 361
06:32:25 INFO - 1498829545265 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49960
06:32:25 INFO - 1498829545266 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:25 INFO - 1498829545266 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545268 Marionette DEBUG Register listener.js for window 7
06:32:25 INFO - 1498829545272 Marionette TRACE 362 <- [1,1,null,{"sessionId":"450fb830-6539-4a29-8ef9-0cb6887b7855","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:25 INFO - 1498829545273 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:32:25 INFO - 1498829545274 Marionette TRACE 362 <- [1,2,null,{}]
06:32:25 INFO - 1498829545276 Marionette DEBUG Closed connection 362
06:32:25 INFO - 1498829545277 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49961
06:32:25 INFO - 1498829545278 Marionette DEBUG Closed connection 363
06:32:25 INFO - 1498829545278 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49962
06:32:25 INFO - 1498829545279 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:32:25 INFO - 1498829545281 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545283 Marionette DEBUG Register listener.js for window 7
06:32:25 INFO - 1498829545286 Marionette TRACE 364 <- [1,1,null,{"sessionId":"6717ae58-d704-4677-8bec-7bfe15ae6f89","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:25 INFO - 1498829545313 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49559/clicks.html"}]
06:32:25 INFO - 1498829545315 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49559/hidden.html"
06:32:25 INFO - 1498829545324 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49559/hidden.html"
06:32:25 INFO - 1498829545324 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49559/hidden.html"
06:32:25 INFO - 1498829545358 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49559/clicks.html"
06:32:25 INFO - 1498829545361 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49559/clicks.html"
06:32:25 INFO - 1498829545377 Marionette TRACE 364 <- [1,2,null,{}]
06:32:25 INFO - 1498829545380 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:32:25 INFO - 1498829545385 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1315fd12-6309-4f2f-84a9-518787e99912","ELEMENT":"1315fd12-6309-4f2f-84a9-518787e99912"}}]
06:32:25 INFO - 1498829545387 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"1315fd12-6309-4f2f-84a9-518787e99912"}]
06:32:25 INFO - 1498829545433 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49559/clicks.html"
06:32:25 INFO - 1498829545448 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49559/clicks.html"
06:32:25 INFO - 1498829545449 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49559/clicks.html"
06:32:25 INFO - 1498829545481 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49559/test.html"
06:32:25 INFO - 1498829545485 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49559/test.html"
06:32:25 INFO - 1498829545490 Marionette TRACE 364 <- [1,4,null,{}]
06:32:25 INFO - 1498829545500 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:32:25 INFO - 1498829545504 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9bf25911-5be7-4741-a50e-bf499f3cef7c","ELEMENT":"9bf25911-5be7-4741-a50e-bf499f3cef7c"}}]
06:32:25 INFO - 1498829545507 Marionette TRACE 364 -> [0,6,"getTitle",{}]
06:32:25 INFO - 1498829545509 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
06:32:25 INFO - 1498829545520 Marionette TRACE 364 -> [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}]
06:32:25 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498829545524 Marionette TRACE 364 <- [1,7,null,{"value":null}]
06:32:25 INFO - 1498829545526 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
06:32:25 INFO - 1498829545528 Marionette TRACE 364 <- [1,8,null,{}]
06:32:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 292ms
06:32:25 INFO - TEST-START | test_click.py TestClick.test_container_element
06:32:25 INFO - 1498829545531 Marionette DEBUG Closed connection 364
06:32:25 INFO - 1498829545532 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49965
06:32:25 INFO - 1498829545534 Marionette DEBUG Closed connection 365
06:32:25 INFO - 1498829545534 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49966
06:32:25 INFO - 1498829545536 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:25 INFO - 1498829545538 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545541 Marionette DEBUG Register listener.js for window 7
06:32:25 INFO - 1498829545547 Marionette TRACE 366 <- [1,1,null,{"sessionId":"bd80083c-a943-4f22-a63e-fd50aa3f96fb","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:25 INFO - 1498829545548 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:32:25 INFO - 1498829545549 Marionette TRACE 366 <- [1,2,null,{}]
06:32:25 INFO - 1498829545551 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:32:25 INFO - 1498829545552 Marionette TRACE 366 <- [1,3,null,{}]
06:32:25 INFO - 1498829545553 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:32:25 INFO - 1498829545554 Marionette TRACE 366 <- [1,4,null,{}]
06:32:25 INFO - 1498829545557 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')","sandbox":"simpletest","line":316}]
06:32:25 INFO - TEST-START: test_click.py TestClick.test_container_element1498829545561 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:32:25 INFO - 1498829545562 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:32:25 INFO - 1498829545564 Marionette TRACE 366 <- [1,6,null,{}]
06:32:25 INFO - 1498829545566 Marionette DEBUG Closed connection 366
06:32:25 INFO - 1498829545568 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49967
06:32:25 INFO - 1498829545569 Marionette DEBUG Closed connection 367
06:32:25 INFO - 1498829545570 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49968
06:32:25 INFO - 1498829545571 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:25 INFO - 1498829545572 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545574 Marionette DEBUG Register listener.js for window 7
06:32:25 INFO - 1498829545579 Marionette TRACE 368 <- [1,1,null,{"sessionId":"64e58ae5-6086-47a1-a3ce-3d9a3e2eefad","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:25 INFO - 1498829545581 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:32:25 INFO - 1498829545582 Marionette TRACE 368 <- [1,2,null,{}]
06:32:25 INFO - 1498829545585 Marionette DEBUG Closed connection 368
06:32:25 INFO - 1498829545585 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49969
06:32:25 INFO - 1498829545587 Marionette DEBUG Closed connection 369
06:32:25 INFO - 1498829545588 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49970
06:32:25 INFO - 1498829545589 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:32:25 INFO - 1498829545590 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545592 Marionette DEBUG Register listener.js for window 7
06:32:25 INFO - 1498829545597 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a33ad54c-0675-4dea-ac2a-514bf9a51140","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:25 INFO - 1498829545598 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%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"}]
06:32:25 INFO - 1498829545600 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49559/test.html"
06:32:25 INFO - 1498829545602 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49559/test.html"
06:32:25 INFO - 1498829545603 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49559/test.html"
06:32:25 INFO - 1498829545625 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"
06:32:25 INFO - 1498829545630 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"
06:32:25 INFO - 1498829545634 Marionette TRACE 370 <- [1,2,null,{}]
06:32:25 INFO - 1498829545645 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:32:25 INFO - 1498829545657 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1d7ae7e9-a31a-418c-8dcd-2808892056e8","ELEMENT":"1d7ae7e9-a31a-418c-8dcd-2808892056e8"}}]
06:32:25 INFO - 1498829545659 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"1d7ae7e9-a31a-418c-8dcd-2808892056e8"}]
06:32:25 INFO - 1498829545905 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:32:25 INFO - 1498829545906 Marionette TRACE 370 <- [1,4,null,{}]
06:32:25 INFO - 1498829545907 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"1d7ae7e9-a31a-418c-8dcd-2808892056e8","name":"selected"}]
06:32:25 INFO - 1498829545923 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:32:25 INFO - 1498829545935 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')","sandbox":"simpletest","line":328}]
06:32:25 INFO - TEST-END: test_click.py TestClick.test_container_element1498829545939 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:32:25 INFO - 1498829545940 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:32:25 INFO - 1498829545942 Marionette TRACE 370 <- [1,7,null,{}]
06:32:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 412ms
06:32:25 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:32:25 INFO - 1498829545944 Marionette DEBUG Closed connection 370
06:32:25 INFO - 1498829545945 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49971
06:32:25 INFO - 1498829545946 Marionette DEBUG Closed connection 371
06:32:25 INFO - 1498829545947 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49972
06:32:25 INFO - 1498829545948 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:25 INFO - 1498829545949 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545952 Marionette DEBUG Register listener.js for window 7
06:32:25 INFO - 1498829545957 Marionette TRACE 372 <- [1,1,null,{"sessionId":"fb0cc48d-ca7f-4e5b-9d59-5c8e4614987b","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:25 INFO - 1498829545958 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:32:25 INFO - 1498829545959 Marionette TRACE 372 <- [1,2,null,{}]
06:32:25 INFO - 1498829545960 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:32:25 INFO - 1498829545960 Marionette TRACE 372 <- [1,3,null,{}]
06:32:25 INFO - 1498829545961 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:32:25 INFO - 1498829545962 Marionette TRACE 372 <- [1,4,null,{}]
06:32:25 INFO - 1498829545964 Marionette TRACE 372 -> [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}]
06:32:25 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498829545967 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:32:25 INFO - 1498829545968 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:32:25 INFO - 1498829545969 Marionette TRACE 372 <- [1,6,null,{}]
06:32:25 INFO - 1498829545971 Marionette DEBUG Closed connection 372
06:32:25 INFO - 1498829545972 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49973
06:32:25 INFO - 1498829545973 Marionette DEBUG Closed connection 373
06:32:25 INFO - 1498829545974 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49974
06:32:25 INFO - 1498829545975 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:25 INFO - 1498829545976 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545978 Marionette DEBUG Register listener.js for window 7
06:32:25 INFO - 1498829545983 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e91d54b5-5b67-4e9e-9d15-8e704245e1e1","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:25 INFO - 1498829545984 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:32:25 INFO - 1498829545986 Marionette TRACE 374 <- [1,2,null,{}]
06:32:25 INFO - 1498829545988 Marionette DEBUG Closed connection 374
06:32:25 INFO - 1498829545990 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49975
06:32:25 INFO - 1498829545991 Marionette DEBUG Closed connection 375
06:32:25 INFO - 1498829545992 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49976
06:32:25 INFO - 1498829545993 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:32:25 INFO - 1498829545995 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:25 INFO - 1498829545997 Marionette DEBUG Register listener.js for window 7
06:32:26 INFO - 1498829546001 Marionette TRACE 376 <- [1,1,null,{"sessionId":"09f4f2c4-1eee-4764-8a6a-bd3f7d0d8cc9","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:26 INFO - 1498829546002 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%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"}]
06:32:26 INFO - 1498829546004 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"
06:32:26 INFO - 1498829546007 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"
06:32:26 INFO - 1498829546008 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"
06:32:26 INFO - 1498829546029 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"
06:32:26 INFO - 1498829546031 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"
06:32:26 INFO - 1498829546035 Marionette TRACE 376 <- [1,2,null,{}]
06:32:26 INFO - 1498829546043 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:32:26 INFO - 1498829546046 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5265fea9-ff34-48f5-ab85-cd76dd9e2c6c","ELEMENT":"5265fea9-ff34-48f5-ab85-cd76dd9e2c6c"}}]
06:32:26 INFO - 1498829546047 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"5265fea9-ff34-48f5-ab85-cd76dd9e2c6c"}]
06:32:26 INFO - 1498829546293 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:32:26 INFO - 1498829546294 Marionette TRACE 376 <- [1,4,null,{}]
06:32:26 INFO - 1498829546296 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"5265fea9-ff34-48f5-ab85-cd76dd9e2c6c","name":"selected"}]
06:32:26 INFO - 1498829546302 Marionette TRACE 376 <- [1,5,null,{"value":true}]
06:32:26 INFO - 1498829546304 Marionette TRACE 376 -> [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}]
06:32:26 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498829546308 Marionette TRACE 376 <- [1,6,null,{"value":null}]
06:32:26 INFO - 1498829546343 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
06:32:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 403ms
06:32:26 INFO - 1498829546345 Marionette TRACE 376 <- [1,7,null,{}]
06:32:26 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:32:26 INFO - 1498829546349 Marionette DEBUG Closed connection 376
06:32:26 INFO - 1498829546350 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49977
06:32:26 INFO - 1498829546352 Marionette DEBUG Closed connection 377
06:32:26 INFO - 1498829546354 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49978
06:32:26 INFO - 1498829546356 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:26 INFO - 1498829546359 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:26 INFO - 1498829546363 Marionette DEBUG Register listener.js for window 7
06:32:26 INFO - 1498829546372 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6623967f-4c91-49c2-acd7-a45ad5d0ed10","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:26 INFO - 1498829546377 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:32:26 INFO - 1498829546393 Marionette TRACE 378 <- [1,2,null,{}]
06:32:26 INFO - 1498829546402 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:32:26 INFO - 1498829546403 Marionette TRACE 378 <- [1,3,null,{}]
06:32:26 INFO - 1498829546411 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:32:26 INFO - 1498829546412 Marionette TRACE 378 <- [1,4,null,{}]
06:32:26 INFO - 1498829546414 Marionette TRACE 378 -> [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}]
06:32:26 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498829546418 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:32:26 INFO - 1498829546420 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:32:26 INFO - 1498829546421 Marionette TRACE 378 <- [1,6,null,{}]
06:32:26 INFO - 1498829546424 Marionette DEBUG Closed connection 378
06:32:26 INFO - 1498829546425 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49979
06:32:26 INFO - 1498829546426 Marionette DEBUG Closed connection 379
06:32:26 INFO - 1498829546427 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49980
06:32:26 INFO - 1498829546428 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:26 INFO - 1498829546429 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:26 INFO - 1498829546432 Marionette DEBUG Register listener.js for window 7
06:32:26 INFO - 1498829546437 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b1ed9072-4ccb-4e61-850e-1cecf1536b41","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:26 INFO - 1498829546439 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:32:26 INFO - 1498829546442 Marionette TRACE 380 <- [1,2,null,{}]
06:32:26 INFO - 1498829546444 Marionette DEBUG Closed connection 380
06:32:26 INFO - 1498829546445 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49981
06:32:26 INFO - 1498829546446 Marionette DEBUG Closed connection 381
06:32:26 INFO - 1498829546447 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49982
06:32:26 INFO - 1498829546448 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:32:26 INFO - 1498829546449 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:26 INFO - 1498829546452 Marionette DEBUG Register listener.js for window 7
06:32:26 INFO - 1498829546456 Marionette TRACE 382 <- [1,1,null,{"sessionId":"586dc3b1-c6c2-4b54-999c-e4bb2ead2928","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:26 INFO - 1498829546457 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%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"}]
06:32:26 INFO - 1498829546459 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"
06:32:26 INFO - 1498829546462 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"
06:32:26 INFO - 1498829546463 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"
06:32:26 INFO - 1498829546504 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"
06:32:26 INFO - 1498829546507 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"
06:32:26 INFO - 1498829546519 Marionette TRACE 382 <- [1,2,null,{}]
06:32:26 INFO - 1498829546522 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:32:26 INFO - 1498829546525 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"63085c57-6544-45fc-9edc-2c1cf232de8b","ELEMENT":"63085c57-6544-45fc-9edc-2c1cf232de8b"}}]
06:32:26 INFO - 1498829546528 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"63085c57-6544-45fc-9edc-2c1cf232de8b"}]
06:32:26 INFO - 1498829546768 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:32:26 INFO - 1498829546769 Marionette TRACE 382 <- [1,4,null,{}]
06:32:26 INFO - 1498829546771 Marionette TRACE 382 -> [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}]
06:32:26 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498829546774 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:32:26 INFO - 1498829546775 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:32:26 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 430ms
06:32:26 INFO - 1498829546777 Marionette TRACE 382 <- [1,6,null,{}]
06:32:26 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:32:26 INFO - 1498829546779 Marionette DEBUG Closed connection 382
06:32:26 INFO - 1498829546781 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49983
06:32:26 INFO - 1498829546781 Marionette DEBUG Closed connection 383
06:32:26 INFO - 1498829546782 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49984
06:32:26 INFO - 1498829546784 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:26 INFO - 1498829546784 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:26 INFO - 1498829546787 Marionette DEBUG Register listener.js for window 7
06:32:26 INFO - 1498829546792 Marionette TRACE 384 <- [1,1,null,{"sessionId":"7ebaa3d5-426c-4917-ba61-fc147db98428","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:26 INFO - 1498829546793 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:32:26 INFO - 1498829546794 Marionette TRACE 384 <- [1,2,null,{}]
06:32:26 INFO - 1498829546795 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:32:26 INFO - 1498829546796 Marionette TRACE 384 <- [1,3,null,{}]
06:32:26 INFO - 1498829546797 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:32:26 INFO - 1498829546797 Marionette TRACE 384 <- [1,4,null,{}]
06:32:26 INFO - 1498829546799 Marionette TRACE 384 -> [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}]
06:32:26 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498829546802 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:32:26 INFO - 1498829546803 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:32:26 INFO - 1498829546805 Marionette TRACE 384 <- [1,6,null,{}]
06:32:26 INFO - 1498829546807 Marionette DEBUG Closed connection 384
06:32:26 INFO - 1498829546808 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49985
06:32:26 INFO - 1498829546809 Marionette DEBUG Closed connection 385
06:32:26 INFO - 1498829546809 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49986
06:32:26 INFO - 1498829546811 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:26 INFO - 1498829546811 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:26 INFO - 1498829546814 Marionette DEBUG Register listener.js for window 7
06:32:26 INFO - 1498829546818 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e60b7eec-6800-455d-a7d5-56f3f8344653","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:26 INFO - 1498829546820 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:32:26 INFO - 1498829546821 Marionette TRACE 386 <- [1,2,null,{}]
06:32:26 INFO - 1498829546824 Marionette DEBUG Closed connection 386
06:32:26 INFO - 1498829546826 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49987
06:32:26 INFO - 1498829546827 Marionette DEBUG Closed connection 387
06:32:26 INFO - 1498829546827 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49988
06:32:26 INFO - 1498829546829 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:32:26 INFO - 1498829546830 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:26 INFO - 1498829546833 Marionette DEBUG Register listener.js for window 7
06:32:26 INFO - 1498829546837 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1862118f-e8dc-41f8-a26c-4ddc6fdea764","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:26 INFO - 1498829546838 Marionette TRACE 388 -> [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"}]
06:32:26 INFO - 1498829546840 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"
06:32:26 INFO - 1498829546843 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"
06:32:26 INFO - 1498829546843 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"
06:32:26 INFO - 1498829546863 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"
06:32:26 INFO - 1498829546867 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"
06:32:26 INFO - 1498829546871 Marionette TRACE 388 <- [1,2,null,{}]
06:32:26 INFO - 1498829546880 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:32:26 INFO - 1498829546884 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5890ad9c-cdbb-4e44-8a5d-973a50ebf789","ELEMENT":"5890ad9c-cdbb-4e44-8a5d-973a50ebf789"}}]
06:32:26 INFO - 1498829546885 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"5890ad9c-cdbb-4e44-8a5d-973a50ebf789"}]
06:32:27 INFO - 1498829547130 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:32:27 INFO - 1498829547131 Marionette TRACE 388 <- [1,4,null,{}]
06:32:27 INFO - 1498829547133 Marionette TRACE 388 -> [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}]
06:32:27 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498829547136 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:32:27 INFO - 1498829547137 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:32:27 INFO - 1498829547139 Marionette TRACE 388 <- [1,6,null,{}]
06:32:27 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 361ms
06:32:27 INFO - TEST-START | test_click.py TestClick.test_input_file
06:32:27 INFO - 1498829547141 Marionette DEBUG Closed connection 388
06:32:27 INFO - 1498829547143 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49989
06:32:27 INFO - 1498829547143 Marionette DEBUG Closed connection 389
06:32:27 INFO - 1498829547144 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49990
06:32:27 INFO - 1498829547145 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:27 INFO - 1498829547146 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547148 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547152 Marionette TRACE 390 <- [1,1,null,{"sessionId":"071f4bbd-8ece-4f3b-acf5-7286749ea7a8","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547153 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:32:27 INFO - 1498829547154 Marionette TRACE 390 <- [1,2,null,{}]
06:32:27 INFO - 1498829547155 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:32:27 INFO - 1498829547155 Marionette TRACE 390 <- [1,3,null,{}]
06:32:27 INFO - 1498829547156 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:32:27 INFO - 1498829547156 Marionette TRACE 390 <- [1,4,null,{}]
06:32:27 INFO - 1498829547158 Marionette TRACE 390 -> [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}]
06:32:27 INFO - TEST-START: test_click.py TestClick.test_input_file1498829547162 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:32:27 INFO - 1498829547163 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:32:27 INFO - 1498829547164 Marionette TRACE 390 <- [1,6,null,{}]
06:32:27 INFO - 1498829547166 Marionette DEBUG Closed connection 390
06:32:27 INFO - 1498829547167 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49991
06:32:27 INFO - 1498829547168 Marionette DEBUG Closed connection 391
06:32:27 INFO - 1498829547168 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49992
06:32:27 INFO - 1498829547169 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:27 INFO - 1498829547170 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547172 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547176 Marionette TRACE 392 <- [1,1,null,{"sessionId":"83858649-6433-434c-a5b5-b9733d9d517d","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547177 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:32:27 INFO - 1498829547178 Marionette TRACE 392 <- [1,2,null,{}]
06:32:27 INFO - 1498829547180 Marionette DEBUG Closed connection 392
06:32:27 INFO - 1498829547181 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49993
06:32:27 INFO - 1498829547182 Marionette DEBUG Closed connection 393
06:32:27 INFO - 1498829547183 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49994
06:32:27 INFO - 1498829547184 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:32:27 INFO - 1498829547185 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547197 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547201 Marionette TRACE 394 <- [1,1,null,{"sessionId":"9abbcd2b-f566-4837-9040-d44bb8aed1a4","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547202 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:32:27 INFO - 1498829547204 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"
06:32:27 INFO - 1498829547208 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"
06:32:27 INFO - 1498829547208 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"
06:32:27 INFO - 1498829547230 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:32:27 INFO - 1498829547232 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:32:27 INFO - 1498829547236 Marionette TRACE 394 <- [1,2,null,{}]
06:32:27 INFO - 1498829547245 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:32:27 INFO - 1498829547248 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a81b878-a8fc-4d6f-8ca0-09048360deff","ELEMENT":"7a81b878-a8fc-4d6f-8ca0-09048360deff"}}]
06:32:27 INFO - 1498829547249 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"7a81b878-a8fc-4d6f-8ca0-09048360deff"}]
06:32:27 INFO - 1498829547277 Marionette TRACE 394 <- [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:1330:5\n"},null]
06:32:27 INFO - 1498829547279 Marionette TRACE 394 -> [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}]
06:32:27 INFO - TEST-END: test_click.py TestClick.test_input_file1498829547282 Marionette TRACE 394 <- [1,5,null,{"value":null}]
06:32:27 INFO - 1498829547283 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
06:32:27 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 145ms
06:32:27 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:32:27 INFO - 1498829547285 Marionette TRACE 394 <- [1,6,null,{}]
06:32:27 INFO - 1498829547287 Marionette DEBUG Closed connection 394
06:32:27 INFO - 1498829547288 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49995
06:32:27 INFO - 1498829547289 Marionette DEBUG Closed connection 395
06:32:27 INFO - 1498829547290 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49996
06:32:27 INFO - 1498829547291 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:27 INFO - 1498829547291 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547294 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547297 Marionette TRACE 396 <- [1,1,null,{"sessionId":"17c5d0e0-b830-4d25-bb4f-5e6abe0e2315","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547299 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:32:27 INFO - 1498829547299 Marionette TRACE 396 <- [1,2,null,{}]
06:32:27 INFO - 1498829547300 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:32:27 INFO - 1498829547301 Marionette TRACE 396 <- [1,3,null,{}]
06:32:27 INFO - 1498829547302 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:32:27 INFO - 1498829547302 Marionette TRACE 396 <- [1,4,null,{}]
06:32:27 INFO - 1498829547304 Marionette TRACE 396 -> [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}]
06:32:27 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498829547307 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:32:27 INFO - 1498829547308 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:32:27 INFO - 1498829547309 Marionette TRACE 396 <- [1,6,null,{}]
06:32:27 INFO - 1498829547312 Marionette DEBUG Closed connection 396
06:32:27 INFO - 1498829547312 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49997
06:32:27 INFO - 1498829547313 Marionette DEBUG Closed connection 397
06:32:27 INFO - 1498829547314 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49998
06:32:27 INFO - 1498829547315 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:27 INFO - 1498829547316 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547320 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547324 Marionette TRACE 398 <- [1,1,null,{"sessionId":"e1a4dade-3eda-4949-8a27-56ac2ad1a8b8","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547325 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:32:27 INFO - 1498829547326 Marionette TRACE 398 <- [1,2,null,{}]
06:32:27 INFO - 1498829547328 Marionette DEBUG Closed connection 398
06:32:27 INFO - 1498829547329 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49999
06:32:27 INFO - 1498829547330 Marionette DEBUG Closed connection 399
06:32:27 INFO - 1498829547330 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50000
06:32:27 INFO - 1498829547331 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:32:27 INFO - 1498829547332 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547335 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547338 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ab0744b4-9853-44c9-af3e-44a7f08cef55","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547340 Marionette TRACE 400 -> [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"}]
06:32:27 INFO - 1498829547341 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:32:27 INFO - 1498829547345 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:32:27 INFO - 1498829547345 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:32:27 INFO - 1498829547369 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"
06:32:27 INFO - 1498829547371 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"
06:32:27 INFO - 1498829547382 Marionette TRACE 400 <- [1,2,null,{}]
06:32:27 INFO - 1498829547384 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:32:27 INFO - 1498829547387 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5bb05713-0887-4c31-97e4-8db73a9c7afc","ELEMENT":"5bb05713-0887-4c31-97e4-8db73a9c7afc"}}]
06:32:27 INFO - 1498829547388 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:32:27 INFO - 1498829547391 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4b118197-1d47-4c2e-b6f0-54bf92552670","ELEMENT":"4b118197-1d47-4c2e-b6f0-54bf92552670"}}]
06:32:27 INFO - 1498829547393 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5bb05713-0887-4c31-97e4-8db73a9c7afc"}]
06:32:27 INFO - 1498829547632 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:32:27 INFO - 1498829547633 Marionette TRACE 400 <- [1,5,null,{}]
06:32:27 INFO - 1498829547634 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"4b118197-1d47-4c2e-b6f0-54bf92552670"}]
06:32:27 INFO - 1498829547650 Marionette TRACE 400 <- [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:1330:5\n"},null]
06:32:27 INFO - 1498829547652 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:32:27 INFO - 1498829547656 Marionette TRACE 400 <- [1,7,null,{"value":false}]
06:32:27 INFO - 1498829547657 Marionette TRACE 400 -> [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}]
06:32:27 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498829547660 Marionette TRACE 400 <- [1,8,null,{"value":null}]
06:32:27 INFO - 1498829547661 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
06:32:27 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 378ms
06:32:27 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:32:27 INFO - 1498829547663 Marionette TRACE 400 <- [1,9,null,{}]
06:32:27 INFO - 1498829547665 Marionette DEBUG Closed connection 400
06:32:27 INFO - 1498829547666 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50001
06:32:27 INFO - 1498829547667 Marionette DEBUG Closed connection 401
06:32:27 INFO - 1498829547667 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50002
06:32:27 INFO - 1498829547669 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:27 INFO - 1498829547669 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547671 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547675 Marionette TRACE 402 <- [1,1,null,{"sessionId":"5c4f6c98-ee3f-4697-9e19-b1b93000adc2","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547676 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
06:32:27 INFO - 1498829547677 Marionette TRACE 402 <- [1,2,null,{}]
06:32:27 INFO - 1498829547678 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:32:27 INFO - 1498829547678 Marionette TRACE 402 <- [1,3,null,{}]
06:32:27 INFO - 1498829547679 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
06:32:27 INFO - 1498829547680 Marionette TRACE 402 <- [1,4,null,{}]
06:32:27 INFO - 1498829547681 Marionette TRACE 402 -> [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}]
06:32:27 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498829547685 Marionette TRACE 402 <- [1,5,null,{"value":null}]
06:32:27 INFO - 1498829547686 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
06:32:27 INFO - 1498829547688 Marionette TRACE 402 <- [1,6,null,{}]
06:32:27 INFO - 1498829547690 Marionette DEBUG Closed connection 402
06:32:27 INFO - 1498829547691 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50003
06:32:27 INFO - 1498829547691 Marionette DEBUG Closed connection 403
06:32:27 INFO - 1498829547692 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50004
06:32:27 INFO - 1498829547693 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:32:27 INFO - 1498829547694 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547696 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547700 Marionette TRACE 404 <- [1,1,null,{"sessionId":"207d28ef-38ea-408a-ad86-78e840de1b07","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547701 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
06:32:27 INFO - 1498829547702 Marionette TRACE 404 <- [1,2,null,{}]
06:32:27 INFO - 1498829547705 Marionette DEBUG Closed connection 404
06:32:27 INFO - 1498829547705 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50005
06:32:27 INFO - 1498829547706 Marionette DEBUG Closed connection 405
06:32:27 INFO - 1498829547707 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50006
06:32:27 INFO - 1498829547708 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:32:27 INFO - 1498829547709 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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}
06:32:27 INFO - 1498829547711 Marionette DEBUG Register listener.js for window 7
06:32:27 INFO - 1498829547714 Marionette TRACE 406 <- [1,1,null,{"sessionId":"600509a5-cfd6-4e06-9d56-fd574a641b51","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":1668,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpajqv2h.mozrunner","moz:accessibilityChecks":false}}]
06:32:27 INFO - 1498829547716 Marionette TRACE 406 -> [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"}]
06:32:27 INFO - 1498829547717 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"
06:32:27 INFO - 1498829547720 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"
06:32:27 INFO - 1498829547720 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"
06:32:27 INFO - 1498829547741 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"
06:32:27 INFO - 1498829547743 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"
06:32:27 INFO - 1498829547747 Marionette TRACE 406 <- [1,2,null,{}]
06:32:27 INFO - 1498829547749 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:32:27 INFO - 1498829547752 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c5cfe1a9-0e72-4905-b446-d8c72c1bcf20","ELEMENT":"c5cfe1a9-0e72-4905-b446-d8c72c1bcf20"}}]
06:32:27 INFO - 1498829547753 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c5cfe1a9-0e72-4905-b446-d8c72c1bcf20"}]
06:32:27 INFO - 1498829547792 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
06:32:27 INFO - 1498829547794 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"c5cfe1a9-0e72-4905-b446-d8c72c1bcf20"}]
06:32:27 INFO - 1498829547815 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element