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]
11:50:23 INFO - 1498848623292 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}]
11:50:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 165ms
11:50:23 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498848623296 Marionette TRACE 358 <- [1,5,null,{"value":null}]
11:50:23 INFO - 1498848623297 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
11:50:23 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
11:50:23 INFO - 1498848623299 Marionette TRACE 358 <- [1,6,null,{}]
11:50:23 INFO - 1498848623301 Marionette DEBUG Closed connection 358
11:50:23 INFO - 1498848623302 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49838
11:50:23 INFO - 1498848623303 Marionette DEBUG Closed connection 359
11:50:23 INFO - 1498848623304 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49839
11:50:23 INFO - 1498848623305 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:23 INFO - 1498848623306 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623309 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623313 Marionette TRACE 360 <- [1,1,null,{"sessionId":"89c7b59b-969a-4125-9ca5-f2802c5500da","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623315 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
11:50:23 INFO - 1498848623315 Marionette TRACE 360 <- [1,2,null,{}]
11:50:23 INFO - 1498848623317 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:50:23 INFO - 1498848623317 Marionette TRACE 360 <- [1,3,null,{}]
11:50:23 INFO - 1498848623319 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
11:50:23 INFO - 1498848623319 Marionette TRACE 360 <- [1,4,null,{}]
11:50:23 INFO - 1498848623321 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}]
11:50:23 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498848623326 Marionette TRACE 360 <- [1,5,null,{"value":null}]
11:50:23 INFO - 1498848623327 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
11:50:23 INFO - 1498848623328 Marionette TRACE 360 <- [1,6,null,{}]
11:50:23 INFO - 1498848623330 Marionette DEBUG Closed connection 360
11:50:23 INFO - 1498848623331 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49840
11:50:23 INFO - 1498848623332 Marionette DEBUG Closed connection 361
11:50:23 INFO - 1498848623333 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49841
11:50:23 INFO - 1498848623334 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:23 INFO - 1498848623335 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623338 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623342 Marionette TRACE 362 <- [1,1,null,{"sessionId":"bfdb6cce-168c-4b74-a45f-1c0d8113b49d","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623343 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
11:50:23 INFO - 1498848623345 Marionette TRACE 362 <- [1,2,null,{}]
11:50:23 INFO - 1498848623348 Marionette DEBUG Closed connection 362
11:50:23 INFO - 1498848623349 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49842
11:50:23 INFO - 1498848623350 Marionette DEBUG Closed connection 363
11:50:23 INFO - 1498848623350 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49843
11:50:23 INFO - 1498848623352 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:50:23 INFO - 1498848623353 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623356 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623360 Marionette TRACE 364 <- [1,1,null,{"sessionId":"65f2dd11-59de-42cf-bbe3-2ef7300aa4a9","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623362 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49436/clicks.html"}]
11:50:23 INFO - 1498848623366 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49436/hidden.html"
11:50:23 INFO - 1498848623379 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49436/hidden.html"
11:50:23 INFO - 1498848623379 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49436/hidden.html"
11:50:23 INFO - 1498848623392 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49436/clicks.html"
11:50:23 INFO - 1498848623395 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49436/clicks.html"
11:50:23 INFO - 1498848623399 Marionette TRACE 364 <- [1,2,null,{}]
11:50:23 INFO - 1498848623411 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
11:50:23 INFO - 1498848623420 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"48feab17-b8fe-4c23-80d7-0a73019733c8","ELEMENT":"48feab17-b8fe-4c23-80d7-0a73019733c8"}}]
11:50:23 INFO - 1498848623422 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"48feab17-b8fe-4c23-80d7-0a73019733c8"}]
11:50:23 INFO - 1498848623467 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49436/clicks.html"
11:50:23 INFO - 1498848623480 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49436/clicks.html"
11:50:23 INFO - 1498848623481 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49436/clicks.html"
11:50:23 INFO - 1498848623502 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49436/test.html"
11:50:23 INFO - 1498848623505 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49436/test.html"
11:50:23 INFO - 1498848623507 Marionette TRACE 364 <- [1,4,null,{}]
11:50:23 INFO - 1498848623512 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
11:50:23 INFO - 1498848623516 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"37515a17-e2a5-40c4-a3b4-7f695c9f1c10","ELEMENT":"37515a17-e2a5-40c4-a3b4-7f695c9f1c10"}}]
11:50:23 INFO - 1498848623517 Marionette TRACE 364 -> [0,6,"getTitle",{}]
11:50:23 INFO - 1498848623519 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
11:50:23 INFO - 1498848623526 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}]
11:50:23 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498848623529 Marionette TRACE 364 <- [1,7,null,{"value":null}]
11:50:23 INFO - 1498848623530 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
11:50:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 233ms
11:50:23 INFO - TEST-START | test_click.py TestClick.test_container_element
11:50:23 INFO - 1498848623532 Marionette TRACE 364 <- [1,8,null,{}]
11:50:23 INFO - 1498848623534 Marionette DEBUG Closed connection 364
11:50:23 INFO - 1498848623535 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49846
11:50:23 INFO - 1498848623536 Marionette DEBUG Closed connection 365
11:50:23 INFO - 1498848623537 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49847
11:50:23 INFO - 1498848623538 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:23 INFO - 1498848623539 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623542 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623546 Marionette TRACE 366 <- [1,1,null,{"sessionId":"efe87d9c-2779-4048-a67b-d0cdacd97dd3","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623548 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
11:50:23 INFO - 1498848623549 Marionette TRACE 366 <- [1,2,null,{}]
11:50:23 INFO - 1498848623550 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:50:23 INFO - 1498848623550 Marionette TRACE 366 <- [1,3,null,{}]
11:50:23 INFO - 1498848623551 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
11:50:23 INFO - 1498848623552 Marionette TRACE 366 <- [1,4,null,{}]
11:50:23 INFO - 1498848623554 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}]
11:50:23 INFO - TEST-START: test_click.py TestClick.test_container_element1498848623558 Marionette TRACE 366 <- [1,5,null,{"value":null}]
11:50:23 INFO - 1498848623559 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
11:50:23 INFO - 1498848623561 Marionette TRACE 366 <- [1,6,null,{}]
11:50:23 INFO - 1498848623562 Marionette DEBUG Closed connection 366
11:50:23 INFO - 1498848623564 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49848
11:50:23 INFO - 1498848623565 Marionette DEBUG Closed connection 367
11:50:23 INFO - 1498848623566 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49849
11:50:23 INFO - 1498848623567 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:23 INFO - 1498848623569 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623574 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623578 Marionette TRACE 368 <- [1,1,null,{"sessionId":"c0076a0b-31c4-40eb-846d-5384b53eab97","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623580 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
11:50:23 INFO - 1498848623581 Marionette TRACE 368 <- [1,2,null,{}]
11:50:23 INFO - 1498848623583 Marionette DEBUG Closed connection 368
11:50:23 INFO - 1498848623584 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49850
11:50:23 INFO - 1498848623585 Marionette DEBUG Closed connection 369
11:50:23 INFO - 1498848623586 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49851
11:50:23 INFO - 1498848623588 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:50:23 INFO - 1498848623589 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623592 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623596 Marionette TRACE 370 <- [1,1,null,{"sessionId":"80332e58-3904-4684-b1b4-fe1233a495bf","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623597 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"}]
11:50:23 INFO - 1498848623600 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49436/test.html"
11:50:23 INFO - 1498848623602 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49436/test.html"
11:50:23 INFO - 1498848623602 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49436/test.html"
11:50:23 INFO - 1498848623610 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"
11:50:23 INFO - 1498848623614 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"
11:50:23 INFO - 1498848623628 Marionette TRACE 370 <- [1,2,null,{}]
11:50:23 INFO - 1498848623635 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:50:23 INFO - 1498848623643 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2c0809c7-8f6b-4d16-9e78-fa89166828a5","ELEMENT":"2c0809c7-8f6b-4d16-9e78-fa89166828a5"}}]
11:50:23 INFO - 1498848623645 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"2c0809c7-8f6b-4d16-9e78-fa89166828a5"}]
11:50:23 INFO - 1498848623893 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:50:23 INFO - 1498848623894 Marionette TRACE 370 <- [1,4,null,{}]
11:50:23 INFO - 1498848623895 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"2c0809c7-8f6b-4d16-9e78-fa89166828a5","name":"selected"}]
11:50:23 INFO - 1498848623901 Marionette TRACE 370 <- [1,5,null,{"value":true}]
11:50:23 INFO - 1498848623903 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}]
11:50:23 INFO - TEST-END: test_click.py TestClick.test_container_element1498848623906 Marionette TRACE 370 <- [1,6,null,{"value":null}]
11:50:23 INFO - 1498848623908 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
11:50:23 INFO - 1498848623909 Marionette TRACE 370 <- [1,7,null,{}]
11:50:23 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 377ms
11:50:23 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
11:50:23 INFO - 1498848623911 Marionette DEBUG Closed connection 370
11:50:23 INFO - 1498848623912 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49852
11:50:23 INFO - 1498848623913 Marionette DEBUG Closed connection 371
11:50:23 INFO - 1498848623914 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49853
11:50:23 INFO - 1498848623915 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:23 INFO - 1498848623916 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623919 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623923 Marionette TRACE 372 <- [1,1,null,{"sessionId":"62bd1820-af08-41e8-96df-9918aa697bea","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623925 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
11:50:23 INFO - 1498848623925 Marionette TRACE 372 <- [1,2,null,{}]
11:50:23 INFO - 1498848623926 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:50:23 INFO - 1498848623927 Marionette TRACE 372 <- [1,3,null,{}]
11:50:23 INFO - 1498848623928 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
11:50:23 INFO - 1498848623928 Marionette TRACE 372 <- [1,4,null,{}]
11:50:23 INFO - 1498848623930 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}]
11:50:23 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498848623934 Marionette TRACE 372 <- [1,5,null,{"value":null}]
11:50:23 INFO - 1498848623935 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
11:50:23 INFO - 1498848623937 Marionette TRACE 372 <- [1,6,null,{}]
11:50:23 INFO - 1498848623939 Marionette DEBUG Closed connection 372
11:50:23 INFO - 1498848623940 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49854
11:50:23 INFO - 1498848623941 Marionette DEBUG Closed connection 373
11:50:23 INFO - 1498848623941 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49855
11:50:23 INFO - 1498848623943 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:23 INFO - 1498848623943 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623947 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623950 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2889c2f9-2c9f-4444-8d2a-c7f30f88d0e9","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623952 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
11:50:23 INFO - 1498848623954 Marionette TRACE 374 <- [1,2,null,{}]
11:50:23 INFO - 1498848623955 Marionette DEBUG Closed connection 374
11:50:23 INFO - 1498848623956 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49856
11:50:23 INFO - 1498848623957 Marionette DEBUG Closed connection 375
11:50:23 INFO - 1498848623958 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49857
11:50:23 INFO - 1498848623959 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:50:23 INFO - 1498848623960 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:23 INFO - 1498848623965 Marionette DEBUG Register listener.js for window 2147483649
11:50:23 INFO - 1498848623969 Marionette TRACE 376 <- [1,1,null,{"sessionId":"56ae1a39-d396-4db6-80ed-93573e3f16fe","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:23 INFO - 1498848623970 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"}]
11:50:23 INFO - 1498848623974 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"
11:50:23 INFO - 1498848623976 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"
11:50:23 INFO - 1498848623977 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"
11:50:23 INFO - 1498848623984 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"
11:50:23 INFO - 1498848623988 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"
11:50:24 INFO - 1498848623998 Marionette TRACE 376 <- [1,2,null,{}]
11:50:24 INFO - 1498848624010 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
11:50:24 INFO - 1498848624013 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d0269df6-c7ef-4c3c-989e-3c5f650d025d","ELEMENT":"d0269df6-c7ef-4c3c-989e-3c5f650d025d"}}]
11:50:24 INFO - 1498848624015 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"d0269df6-c7ef-4c3c-989e-3c5f650d025d"}]
11:50:24 INFO - 1498848624263 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:50:24 INFO - 1498848624264 Marionette TRACE 376 <- [1,4,null,{}]
11:50:24 INFO - 1498848624266 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"d0269df6-c7ef-4c3c-989e-3c5f650d025d","name":"selected"}]
11:50:24 INFO - 1498848624271 Marionette TRACE 376 <- [1,5,null,{"value":true}]
11:50:24 INFO - 1498848624273 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}]
11:50:24 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498848624278 Marionette TRACE 376 <- [1,6,null,{"value":null}]
11:50:24 INFO - 1498848624279 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
11:50:24 INFO - 1498848624281 Marionette TRACE 376 <- [1,7,null,{}]
11:50:24 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 371ms
11:50:24 INFO - TEST-START | test_click.py TestClick.test_css_transforms
11:50:24 INFO - 1498848624283 Marionette DEBUG Closed connection 376
11:50:24 INFO - 1498848624284 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49858
11:50:24 INFO - 1498848624285 Marionette DEBUG Closed connection 377
11:50:24 INFO - 1498848624286 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49859
11:50:24 INFO - 1498848624287 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:24 INFO - 1498848624288 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:24 INFO - 1498848624291 Marionette DEBUG Register listener.js for window 2147483649
11:50:24 INFO - 1498848624295 Marionette TRACE 378 <- [1,1,null,{"sessionId":"2ebe7819-9d57-45d7-892a-77165ca2f5c7","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:24 INFO - 1498848624296 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
11:50:24 INFO - 1498848624297 Marionette TRACE 378 <- [1,2,null,{}]
11:50:24 INFO - 1498848624298 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:50:24 INFO - 1498848624298 Marionette TRACE 378 <- [1,3,null,{}]
11:50:24 INFO - 1498848624299 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
11:50:24 INFO - 1498848624300 Marionette TRACE 378 <- [1,4,null,{}]
11:50:24 INFO - 1498848624302 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}]
11:50:24 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498848624305 Marionette TRACE 378 <- [1,5,null,{"value":null}]
11:50:24 INFO - 1498848624307 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
11:50:24 INFO - 1498848624308 Marionette TRACE 378 <- [1,6,null,{}]
11:50:24 INFO - 1498848624310 Marionette DEBUG Closed connection 378
11:50:24 INFO - 1498848624311 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49860
11:50:24 INFO - 1498848624312 Marionette DEBUG Closed connection 379
11:50:24 INFO - 1498848624313 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49861
11:50:24 INFO - 1498848624314 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:24 INFO - 1498848624315 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:24 INFO - 1498848624318 Marionette DEBUG Register listener.js for window 2147483649
11:50:24 INFO - 1498848624322 Marionette TRACE 380 <- [1,1,null,{"sessionId":"6fbdbc25-5324-407d-a0e8-3679071b08ca","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:24 INFO - 1498848624323 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
11:50:24 INFO - 1498848624325 Marionette TRACE 380 <- [1,2,null,{}]
11:50:24 INFO - 1498848624327 Marionette DEBUG Closed connection 380
11:50:24 INFO - 1498848624328 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49862
11:50:24 INFO - 1498848624329 Marionette DEBUG Closed connection 381
11:50:24 INFO - 1498848624330 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49863
11:50:24 INFO - 1498848624331 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:50:24 INFO - 1498848624332 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:24 INFO - 1498848624335 Marionette DEBUG Register listener.js for window 2147483649
11:50:24 INFO - 1498848624339 Marionette TRACE 382 <- [1,1,null,{"sessionId":"63679ee2-dd83-49b1-b694-b49123d7d666","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:24 INFO - 1498848624340 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"}]
11:50:24 INFO - 1498848624343 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"
11:50:24 INFO - 1498848624345 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"
11:50:24 INFO - 1498848624346 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"
11:50:24 INFO - 1498848624353 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"
11:50:24 INFO - 1498848624357 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"
11:50:24 INFO - 1498848624368 Marionette TRACE 382 <- [1,2,null,{}]
11:50:24 INFO - 1498848624379 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
11:50:24 INFO - 1498848624383 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c6cd9ab8-8b5a-4de8-a00c-9f7edcbd49df","ELEMENT":"c6cd9ab8-8b5a-4de8-a00c-9f7edcbd49df"}}]
11:50:24 INFO - 1498848624385 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c6cd9ab8-8b5a-4de8-a00c-9f7edcbd49df"}]
11:50:24 INFO - 1498848624629 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:50:24 INFO - 1498848624630 Marionette TRACE 382 <- [1,4,null,{}]
11:50:24 INFO - 1498848624632 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}]
11:50:24 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498848624636 Marionette TRACE 382 <- [1,5,null,{"value":null}]
11:50:24 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 357ms
11:50:24 INFO - 1498848624637 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
11:50:24 INFO - 1498848624639 Marionette TRACE 382 <- [1,6,null,{}]
11:50:24 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
11:50:24 INFO - 1498848624640 Marionette DEBUG Closed connection 382
11:50:24 INFO - 1498848624642 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49864
11:50:24 INFO - 1498848624643 Marionette DEBUG Closed connection 383
11:50:24 INFO - 1498848624643 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49865
11:50:24 INFO - 1498848624645 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:24 INFO - 1498848624645 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:24 INFO - 1498848624649 Marionette DEBUG Register listener.js for window 2147483649
11:50:24 INFO - 1498848624653 Marionette TRACE 384 <- [1,1,null,{"sessionId":"60285f51-48f6-402c-a1dd-13629b05b5a3","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:24 INFO - 1498848624654 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
11:50:24 INFO - 1498848624655 Marionette TRACE 384 <- [1,2,null,{}]
11:50:24 INFO - 1498848624656 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:50:24 INFO - 1498848624656 Marionette TRACE 384 <- [1,3,null,{}]
11:50:24 INFO - 1498848624658 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
11:50:24 INFO - 1498848624658 Marionette TRACE 384 <- [1,4,null,{}]
11:50:24 INFO - 1498848624660 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}]
11:50:24 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498848624664 Marionette TRACE 384 <- [1,5,null,{"value":null}]
11:50:24 INFO - 1498848624665 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
11:50:24 INFO - 1498848624667 Marionette TRACE 384 <- [1,6,null,{}]
11:50:24 INFO - 1498848624668 Marionette DEBUG Closed connection 384
11:50:24 INFO - 1498848624669 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49866
11:50:24 INFO - 1498848624670 Marionette DEBUG Closed connection 385
11:50:24 INFO - 1498848624671 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49867
11:50:24 INFO - 1498848624672 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:24 INFO - 1498848624673 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:24 INFO - 1498848624682 Marionette DEBUG Register listener.js for window 2147483649
11:50:24 INFO - 1498848624686 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d06bb76c-53e7-44b2-8293-46a1168cf85d","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:24 INFO - 1498848624698 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
11:50:24 INFO - 1498848624700 Marionette TRACE 386 <- [1,2,null,{}]
11:50:24 INFO - 1498848624702 Marionette DEBUG Closed connection 386
11:50:24 INFO - 1498848624702 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49868
11:50:24 INFO - 1498848624705 Marionette DEBUG Closed connection 387
11:50:24 INFO - 1498848624706 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49869
11:50:24 INFO - 1498848624707 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:50:24 INFO - 1498848624708 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:24 INFO - 1498848624711 Marionette DEBUG Register listener.js for window 2147483649
11:50:24 INFO - 1498848624715 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4d09bec7-ae4e-4850-8e81-0df63a2519c8","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:24 INFO - 1498848624717 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"}]
11:50:24 INFO - 1498848624719 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"
11:50:24 INFO - 1498848624722 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"
11:50:24 INFO - 1498848624722 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"
11:50:24 INFO - 1498848624730 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"
11:50:24 INFO - 1498848624734 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"
11:50:24 INFO - 1498848624761 Marionette TRACE 388 <- [1,2,null,{}]
11:50:24 INFO - 1498848624767 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
11:50:24 INFO - 1498848624779 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ee7da7e9-e121-4d98-8745-c9b39e532228","ELEMENT":"ee7da7e9-e121-4d98-8745-c9b39e532228"}}]
11:50:24 INFO - 1498848624780 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"ee7da7e9-e121-4d98-8745-c9b39e532228"}]
11:50:25 INFO - 1498848625029 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:50:25 INFO - 1498848625030 Marionette TRACE 388 <- [1,4,null,{}]
11:50:25 INFO - 1498848625032 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}]
11:50:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498848625036 Marionette TRACE 388 <- [1,5,null,{"value":null}]
11:50:25 INFO - 1498848625037 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
11:50:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 399ms
11:50:25 INFO - TEST-START | test_click.py TestClick.test_input_file
11:50:25 INFO - 1498848625039 Marionette TRACE 388 <- [1,6,null,{}]
11:50:25 INFO - 1498848625041 Marionette DEBUG Closed connection 388
11:50:25 INFO - 1498848625043 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49870
11:50:25 INFO - 1498848625044 Marionette DEBUG Closed connection 389
11:50:25 INFO - 1498848625050 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49871
11:50:25 INFO - 1498848625051 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:25 INFO - 1498848625053 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625057 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625061 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d9a8b74e-c384-4748-97ca-bd42f871410c","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625063 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
11:50:25 INFO - 1498848625064 Marionette TRACE 390 <- [1,2,null,{}]
11:50:25 INFO - 1498848625066 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:50:25 INFO - 1498848625066 Marionette TRACE 390 <- [1,3,null,{}]
11:50:25 INFO - 1498848625068 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
11:50:25 INFO - 1498848625068 Marionette TRACE 390 <- [1,4,null,{}]
11:50:25 INFO - 1498848625070 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}]
11:50:25 INFO - TEST-START: test_click.py TestClick.test_input_file1498848625074 Marionette TRACE 390 <- [1,5,null,{"value":null}]
11:50:25 INFO - 1498848625075 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
11:50:25 INFO - 1498848625077 Marionette TRACE 390 <- [1,6,null,{}]
11:50:25 INFO - 1498848625079 Marionette DEBUG Closed connection 390
11:50:25 INFO - 1498848625080 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49872
11:50:25 INFO - 1498848625081 Marionette DEBUG Closed connection 391
11:50:25 INFO - 1498848625082 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49873
11:50:25 INFO - 1498848625083 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:25 INFO - 1498848625084 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625088 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625091 Marionette TRACE 392 <- [1,1,null,{"sessionId":"b4da4180-1e24-4da3-b4e8-b5a316601bab","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625093 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
11:50:25 INFO - 1498848625094 Marionette TRACE 392 <- [1,2,null,{}]
11:50:25 INFO - 1498848625096 Marionette DEBUG Closed connection 392
11:50:25 INFO - 1498848625097 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49874
11:50:25 INFO - 1498848625098 Marionette DEBUG Closed connection 393
11:50:25 INFO - 1498848625099 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49875
11:50:25 INFO - 1498848625100 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:50:25 INFO - 1498848625101 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625104 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625108 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a6d992b8-2787-49d6-96b9-bf5622d7b8ed","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625109 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
11:50:25 INFO - 1498848625111 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"
11:50:25 INFO - 1498848625115 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"
11:50:25 INFO - 1498848625116 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"
11:50:25 INFO - 1498848625126 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:50:25 INFO - 1498848625134 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:50:25 INFO - 1498848625136 Marionette TRACE 394 <- [1,2,null,{}]
11:50:25 INFO - 1498848625151 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
11:50:25 INFO - 1498848625160 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ac14906d-379c-4476-a8cd-32c53a197c46","ELEMENT":"ac14906d-379c-4476-a8cd-32c53a197c46"}}]
11:50:25 INFO - 1498848625162 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"ac14906d-379c-4476-a8cd-32c53a197c46"}]
11:50:25 INFO - 1498848625191 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]
11:50:25 INFO - 1498848625193 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}]
11:50:25 INFO - TEST-END: test_click.py TestClick.test_input_file1498848625196 Marionette TRACE 394 <- [1,5,null,{"value":null}]
11:50:25 INFO - 1498848625197 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
11:50:25 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 160ms
11:50:25 INFO - TEST-START | test_click.py TestClick.test_obscured_element
11:50:25 INFO - 1498848625199 Marionette TRACE 394 <- [1,6,null,{}]
11:50:25 INFO - 1498848625201 Marionette DEBUG Closed connection 394
11:50:25 INFO - 1498848625202 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49876
11:50:25 INFO - 1498848625203 Marionette DEBUG Closed connection 395
11:50:25 INFO - 1498848625204 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49877
11:50:25 INFO - 1498848625205 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:25 INFO - 1498848625205 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625209 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625212 Marionette TRACE 396 <- [1,1,null,{"sessionId":"7f63eeff-c811-44cf-bc06-9b85b617fd8b","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625214 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
11:50:25 INFO - 1498848625214 Marionette TRACE 396 <- [1,2,null,{}]
11:50:25 INFO - 1498848625215 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:50:25 INFO - 1498848625216 Marionette TRACE 396 <- [1,3,null,{}]
11:50:25 INFO - 1498848625217 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
11:50:25 INFO - 1498848625217 Marionette TRACE 396 <- [1,4,null,{}]
11:50:25 INFO - 1498848625219 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}]
11:50:25 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498848625223 Marionette TRACE 396 <- [1,5,null,{"value":null}]
11:50:25 INFO - 1498848625224 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
11:50:25 INFO - 1498848625226 Marionette TRACE 396 <- [1,6,null,{}]
11:50:25 INFO - 1498848625227 Marionette DEBUG Closed connection 396
11:50:25 INFO - 1498848625228 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49878
11:50:25 INFO - 1498848625230 Marionette DEBUG Closed connection 397
11:50:25 INFO - 1498848625231 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49879
11:50:25 INFO - 1498848625232 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:25 INFO - 1498848625233 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625236 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625240 Marionette TRACE 398 <- [1,1,null,{"sessionId":"6f1494e5-7ab3-49ee-9e9d-453ee9027907","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625241 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
11:50:25 INFO - 1498848625244 Marionette TRACE 398 <- [1,2,null,{}]
11:50:25 INFO - 1498848625245 Marionette DEBUG Closed connection 398
11:50:25 INFO - 1498848625246 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49880
11:50:25 INFO - 1498848625247 Marionette DEBUG Closed connection 399
11:50:25 INFO - 1498848625248 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49881
11:50:25 INFO - 1498848625249 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:50:25 INFO - 1498848625250 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625253 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625257 Marionette TRACE 400 <- [1,1,null,{"sessionId":"6e388a46-e583-4cac-a559-c1cb63ed5722","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625258 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"}]
11:50:25 INFO - 1498848625261 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:50:25 INFO - 1498848625263 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:50:25 INFO - 1498848625264 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
11:50:25 INFO - 1498848625284 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"
11:50:25 INFO - 1498848625286 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"
11:50:25 INFO - 1498848625299 Marionette TRACE 400 <- [1,2,null,{}]
11:50:25 INFO - 1498848625311 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
11:50:25 INFO - 1498848625314 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f4c0b96a-2636-4d04-95f3-39acfb6eda68","ELEMENT":"f4c0b96a-2636-4d04-95f3-39acfb6eda68"}}]
11:50:25 INFO - 1498848625316 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
11:50:25 INFO - 1498848625319 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4419dcae-748a-4c49-86a6-aaace0c1a49b","ELEMENT":"4419dcae-748a-4c49-86a6-aaace0c1a49b"}}]
11:50:25 INFO - 1498848625320 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f4c0b96a-2636-4d04-95f3-39acfb6eda68"}]
11:50:25 INFO - 1498848625563 Marionette DEBUG Canceled page load listener because no navigation has been detected
11:50:25 INFO - 1498848625564 Marionette TRACE 400 <- [1,5,null,{}]
11:50:25 INFO - 1498848625565 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"4419dcae-748a-4c49-86a6-aaace0c1a49b"}]
11:50:25 INFO - 1498848625584 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]
11:50:25 INFO - 1498848625587 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
11:50:25 INFO - 1498848625591 Marionette TRACE 400 <- [1,7,null,{"value":false}]
11:50:25 INFO - 1498848625593 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}]
11:50:25 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498848625597 Marionette TRACE 400 <- [1,8,null,{"value":null}]
11:50:25 INFO - 1498848625598 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
11:50:25 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 400ms
11:50:25 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
11:50:25 INFO - 1498848625600 Marionette TRACE 400 <- [1,9,null,{}]
11:50:25 INFO - 1498848625601 Marionette DEBUG Closed connection 400
11:50:25 INFO - 1498848625602 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49882
11:50:25 INFO - 1498848625603 Marionette DEBUG Closed connection 401
11:50:25 INFO - 1498848625604 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49883
11:50:25 INFO - 1498848625605 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:25 INFO - 1498848625606 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625609 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625613 Marionette TRACE 402 <- [1,1,null,{"sessionId":"71fdfcc2-56b3-469f-b832-c9674c8ad314","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625615 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
11:50:25 INFO - 1498848625615 Marionette TRACE 402 <- [1,2,null,{}]
11:50:25 INFO - 1498848625616 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
11:50:25 INFO - 1498848625617 Marionette TRACE 402 <- [1,3,null,{}]
11:50:25 INFO - 1498848625618 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
11:50:25 INFO - 1498848625619 Marionette TRACE 402 <- [1,4,null,{}]
11:50:25 INFO - 1498848625621 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}]
11:50:25 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498848625624 Marionette TRACE 402 <- [1,5,null,{"value":null}]
11:50:25 INFO - 1498848625626 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
11:50:25 INFO - 1498848625627 Marionette TRACE 402 <- [1,6,null,{}]
11:50:25 INFO - 1498848625629 Marionette DEBUG Closed connection 402
11:50:25 INFO - 1498848625630 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49884
11:50:25 INFO - 1498848625631 Marionette DEBUG Closed connection 403
11:50:25 INFO - 1498848625633 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49885
11:50:25 INFO - 1498848625634 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
11:50:25 INFO - 1498848625635 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625638 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625642 Marionette TRACE 404 <- [1,1,null,{"sessionId":"3064fcdb-50ac-4113-a06b-8b18f8e2ce58","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625643 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
11:50:25 INFO - 1498848625645 Marionette TRACE 404 <- [1,2,null,{}]
11:50:25 INFO - 1498848625647 Marionette DEBUG Closed connection 404
11:50:25 INFO - 1498848625647 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49886
11:50:25 INFO - 1498848625648 Marionette DEBUG Closed connection 405
11:50:25 INFO - 1498848625649 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49887
11:50:25 INFO - 1498848625653 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
11:50:25 INFO - 1498848625654 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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}
11:50:25 INFO - 1498848625657 Marionette DEBUG Register listener.js for window 2147483649
11:50:25 INFO - 1498848625664 Marionette TRACE 406 <- [1,1,null,{"sessionId":"48433c8a-a19c-4521-92f9-25faaff56a0d","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":3588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmptvw6db.mozrunner","moz:accessibilityChecks":false}}]
11:50:25 INFO - 1498848625666 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"}]
11:50:25 INFO - 1498848625668 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"
11:50:25 INFO - 1498848625671 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"
11:50:25 INFO - 1498848625671 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"
11:50:25 INFO - 1498848625680 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"
11:50:25 INFO - 1498848625683 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"
11:50:25 INFO - 1498848625695 Marionette TRACE 406 <- [1,2,null,{}]
11:50:25 INFO - 1498848625701 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
11:50:25 INFO - 1498848625709 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"177fe058-8570-470f-99ec-d44b0698b7e2","ELEMENT":"177fe058-8570-470f-99ec-d44b0698b7e2"}}]
11:50:25 INFO - 1498848625711 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"177fe058-8570-470f-99ec-d44b0698b7e2"}]
11:50:25 INFO - 1498848625742 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
11:50:25 INFO - 1498848625743 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"177fe058-8570-470f-99ec-d44b0698b7e2"}]
11:50:25 INFO - 1498848625763 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element