could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
14:05:16 INFO - 1499720716479 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
14:05:16 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
14:05:16 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499720716482 Marionette TRACE 352 <- [1,5,null,{"value":null}]
14:05:16 INFO - 1499720716483 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
14:05:16 INFO - 1499720716484 Marionette TRACE 352 <- [1,6,null,{}]
14:05:16 INFO - 1499720716486 Marionette DEBUG Closed connection 352
14:05:16 INFO - 1499720716487 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49948
14:05:16 INFO - 1499720716488 Marionette DEBUG Closed connection 353
14:05:16 INFO - 1499720716489 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49949
14:05:16 INFO - 1499720716490 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:16 INFO - 1499720716490 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:16 INFO - 1499720716492 Marionette DEBUG Register listener.js for window 4294967297
14:05:16 INFO - 1499720716495 Marionette TRACE 354 <- [1,1,null,{"sessionId":"fad5cd10-69a3-4791-a616-17eefb5c5411","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:16 INFO - 1499720716497 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
14:05:16 INFO - 1499720716497 Marionette TRACE 354 <- [1,2,null,{}]
14:05:16 INFO - 1499720716499 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:05:16 INFO - 1499720716499 Marionette TRACE 354 <- [1,3,null,{}]
14:05:16 INFO - 1499720716501 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
14:05:16 INFO - 1499720716501 Marionette TRACE 354 <- [1,4,null,{}]
14:05:16 INFO - 1499720716503 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
14:05:16 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499720716506 Marionette TRACE 354 <- [1,5,null,{"value":null}]
14:05:16 INFO - 1499720716507 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
14:05:16 INFO - 1499720716508 Marionette TRACE 354 <- [1,6,null,{}]
14:05:16 INFO - 1499720716509 Marionette DEBUG Closed connection 354
14:05:16 INFO - 1499720716510 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49950
14:05:16 INFO - 1499720716510 Marionette DEBUG Closed connection 355
14:05:16 INFO - 1499720716511 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49951
14:05:16 INFO - 1499720716512 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:16 INFO - 1499720716513 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:16 INFO - 1499720716515 Marionette DEBUG Register listener.js for window 4294967297
14:05:16 INFO - 1499720716518 Marionette TRACE 356 <- [1,1,null,{"sessionId":"c3609b07-90f0-454c-b88a-ef6d3b4512f1","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:16 INFO - 1499720716519 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
14:05:16 INFO - 1499720716520 Marionette TRACE 356 <- [1,2,null,{}]
14:05:16 INFO - 1499720716521 Marionette DEBUG Closed connection 356
14:05:16 INFO - 1499720716522 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49952
14:05:16 INFO - 1499720716522 Marionette DEBUG Closed connection 357
14:05:16 INFO - 1499720716523 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49953
14:05:16 INFO - 1499720716524 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:05:16 INFO - 1499720716524 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:16 INFO - 1499720716533 Marionette DEBUG Register listener.js for window 4294967297
14:05:16 INFO - 1499720716536 Marionette TRACE 358 <- [1,1,null,{"sessionId":"15c06577-9f46-4be1-917b-860964e3ebc5","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:16 INFO - 1499720716537 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49511/clicks.html"}]
14:05:16 INFO - 1499720716541 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49511/hidden.html"
14:05:16 INFO - 1499720716548 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49511/hidden.html"
14:05:16 INFO - 1499720716548 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49511/hidden.html"
14:05:16 INFO - 1499720716554 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49511/clicks.html"
14:05:16 INFO - 1499720716559 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49511/clicks.html"
14:05:16 INFO - 1499720716563 Marionette TRACE 358 <- [1,2,null,{}]
14:05:16 INFO - 1499720716567 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
14:05:16 INFO - 1499720716569 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"09ddd09e-3966-4be5-8f1a-de1806c7d8b9","ELEMENT":"09ddd09e-3966-4be5-8f1a-de1806c7d8b9"}}]
14:05:16 INFO - 1499720716570 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"09ddd09e-3966-4be5-8f1a-de1806c7d8b9"}]
14:05:16 INFO - 1499720716606 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49511/clicks.html"
14:05:16 INFO - 1499720716617 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49511/clicks.html"
14:05:16 INFO - 1499720716618 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49511/clicks.html"
14:05:16 INFO - 1499720716624 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49511/test.html"
14:05:16 INFO - 1499720716628 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49511/test.html"
14:05:16 INFO - 1499720716637 Marionette TRACE 358 <- [1,4,null,{}]
14:05:16 INFO - 1499720716653 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
14:05:16 INFO - 1499720716656 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4b6ee34c-9bf9-4a61-a60a-1e4d58c06bcd","ELEMENT":"4b6ee34c-9bf9-4a61-a60a-1e4d58c06bcd"}}]
14:05:16 INFO - 1499720716657 Marionette TRACE 358 -> [0,6,"getTitle",{}]
14:05:16 INFO - 1499720716657 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
14:05:16 INFO - 1499720716659 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
14:05:16 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499720716661 Marionette TRACE 358 <- [1,7,null,{"value":null}]
14:05:16 INFO - 1499720716662 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
14:05:16 INFO - 1499720716664 Marionette TRACE 358 <- [1,8,null,{}]
14:05:16 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 179ms
14:05:16 INFO - TEST-START | test_click.py TestClick.test_container_element
14:05:16 INFO - 1499720716665 Marionette DEBUG Closed connection 358
14:05:16 INFO - 1499720716666 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49956
14:05:16 INFO - 1499720716666 Marionette DEBUG Closed connection 359
14:05:16 INFO - 1499720716667 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49957
14:05:16 INFO - 1499720716668 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:16 INFO - 1499720716669 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:16 INFO - 1499720716671 Marionette DEBUG Register listener.js for window 4294967297
14:05:16 INFO - 1499720716674 Marionette TRACE 360 <- [1,1,null,{"sessionId":"aeac0013-2ba1-4952-8996-39d177438a5b","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:16 INFO - 1499720716675 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
14:05:16 INFO - 1499720716675 Marionette TRACE 360 <- [1,2,null,{}]
14:05:16 INFO - 1499720716676 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:05:16 INFO - 1499720716676 Marionette TRACE 360 <- [1,3,null,{}]
14:05:16 INFO - 1499720716677 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
14:05:16 INFO - 1499720716677 Marionette TRACE 360 <- [1,4,null,{}]
14:05:16 INFO - 1499720716679 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
14:05:16 INFO - TEST-START: test_click.py TestClick.test_container_element1499720716683 Marionette TRACE 360 <- [1,5,null,{"value":null}]
14:05:16 INFO - 1499720716683 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
14:05:16 INFO - 1499720716684 Marionette TRACE 360 <- [1,6,null,{}]
14:05:16 INFO - 1499720716686 Marionette DEBUG Closed connection 360
14:05:16 INFO - 1499720716687 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49958
14:05:16 INFO - 1499720716687 Marionette DEBUG Closed connection 361
14:05:16 INFO - 1499720716688 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49959
14:05:16 INFO - 1499720716689 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:16 INFO - 1499720716689 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:16 INFO - 1499720716691 Marionette DEBUG Register listener.js for window 4294967297
14:05:16 INFO - 1499720716694 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b70ec2d2-748d-4e34-ad22-ca5b84e4634b","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:16 INFO - 1499720716695 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
14:05:16 INFO - 1499720716696 Marionette TRACE 362 <- [1,2,null,{}]
14:05:16 INFO - 1499720716698 Marionette DEBUG Closed connection 362
14:05:16 INFO - 1499720716698 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49960
14:05:16 INFO - 1499720716699 Marionette DEBUG Closed connection 363
14:05:16 INFO - 1499720716700 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49961
14:05:16 INFO - 1499720716700 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:05:16 INFO - 1499720716701 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:16 INFO - 1499720716703 Marionette DEBUG Register listener.js for window 4294967297
14:05:16 INFO - 1499720716706 Marionette TRACE 364 <- [1,1,null,{"sessionId":"9cdd642e-78b8-45c8-819c-16be2737b761","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:16 INFO - 1499720716707 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
14:05:16 INFO - 1499720716708 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49511/test.html"
14:05:16 INFO - 1499720716710 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49511/test.html"
14:05:16 INFO - 1499720716710 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49511/test.html"
14:05:16 INFO - 1499720716716 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"
14:05:16 INFO - 1499720716719 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"
14:05:16 INFO - 1499720716725 Marionette TRACE 364 <- [1,2,null,{}]
14:05:16 INFO - 1499720716734 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:05:16 INFO - 1499720716736 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8fb53c5d-cbf1-4a15-885b-9435f40034cc","ELEMENT":"8fb53c5d-cbf1-4a15-885b-9435f40034cc"}}]
14:05:16 INFO - 1499720716737 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8fb53c5d-cbf1-4a15-885b-9435f40034cc"}]
14:05:16 INFO - 1499720716976 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:05:16 INFO - 1499720716977 Marionette TRACE 364 <- [1,4,null,{}]
14:05:16 INFO - 1499720716978 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8fb53c5d-cbf1-4a15-885b-9435f40034cc","name":"selected"}]
14:05:16 INFO - 1499720716982 Marionette TRACE 364 <- [1,5,null,{"value":true}]
14:05:16 INFO - 1499720716984 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
14:05:16 INFO - TEST-END: test_click.py TestClick.test_container_element1499720716986 Marionette TRACE 364 <- [1,6,null,{"value":null}]
14:05:16 INFO - 1499720716987 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
14:05:16 INFO - 1499720716988 Marionette TRACE 364 <- [1,7,null,{}]
14:05:16 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 325ms
14:05:16 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
14:05:16 INFO - 1499720716990 Marionette DEBUG Closed connection 364
14:05:16 INFO - 1499720716991 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49962
14:05:16 INFO - 1499720716991 Marionette DEBUG Closed connection 365
14:05:16 INFO - 1499720716992 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49963
14:05:16 INFO - 1499720716993 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:16 INFO - 1499720716994 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:16 INFO - 1499720716996 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720716998 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3fa6e162-921d-492d-bac1-6743e4e4b79d","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720716999 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
14:05:17 INFO - 1499720717000 Marionette TRACE 366 <- [1,2,null,{}]
14:05:17 INFO - 1499720717001 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:05:17 INFO - 1499720717001 Marionette TRACE 366 <- [1,3,null,{}]
14:05:17 INFO - 1499720717002 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
14:05:17 INFO - 1499720717002 Marionette TRACE 366 <- [1,4,null,{}]
14:05:17 INFO - 1499720717004 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
14:05:17 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499720717006 Marionette TRACE 366 <- [1,5,null,{"value":null}]
14:05:17 INFO - 1499720717008 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
14:05:17 INFO - 1499720717009 Marionette TRACE 366 <- [1,6,null,{}]
14:05:17 INFO - 1499720717011 Marionette DEBUG Closed connection 366
14:05:17 INFO - 1499720717012 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49964
14:05:17 INFO - 1499720717012 Marionette DEBUG Closed connection 367
14:05:17 INFO - 1499720717013 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49965
14:05:17 INFO - 1499720717014 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:17 INFO - 1499720717015 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717016 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717019 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b07e6356-f578-46eb-a3dc-72edc9c88a10","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717020 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
14:05:17 INFO - 1499720717021 Marionette TRACE 368 <- [1,2,null,{}]
14:05:17 INFO - 1499720717022 Marionette DEBUG Closed connection 368
14:05:17 INFO - 1499720717023 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49966
14:05:17 INFO - 1499720717024 Marionette DEBUG Closed connection 369
14:05:17 INFO - 1499720717024 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49967
14:05:17 INFO - 1499720717025 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:05:17 INFO - 1499720717026 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717028 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717030 Marionette TRACE 370 <- [1,1,null,{"sessionId":"976b44d3-31f3-431d-a97c-c35f38e80103","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717032 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
14:05:17 INFO - 1499720717033 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"
14:05:17 INFO - 1499720717035 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"
14:05:17 INFO - 1499720717035 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"
14:05:17 INFO - 1499720717041 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"
14:05:17 INFO - 1499720717045 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"
14:05:17 INFO - 1499720717050 Marionette TRACE 370 <- [1,2,null,{}]
14:05:17 INFO - 1499720717053 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:05:17 INFO - 1499720717056 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93f2a75e-561d-4c33-8b62-7c38f87cf698","ELEMENT":"93f2a75e-561d-4c33-8b62-7c38f87cf698"}}]
14:05:17 INFO - 1499720717057 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"93f2a75e-561d-4c33-8b62-7c38f87cf698"}]
14:05:17 INFO - 1499720717297 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:05:17 INFO - 1499720717298 Marionette TRACE 370 <- [1,4,null,{}]
14:05:17 INFO - 1499720717299 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"93f2a75e-561d-4c33-8b62-7c38f87cf698","name":"selected"}]
14:05:17 INFO - 1499720717303 Marionette TRACE 370 <- [1,5,null,{"value":true}]
14:05:17 INFO - 1499720717305 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
14:05:17 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499720717307 Marionette TRACE 370 <- [1,6,null,{"value":null}]
14:05:17 INFO - 1499720717308 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
14:05:17 INFO - 1499720717309 Marionette TRACE 370 <- [1,7,null,{}]
14:05:17 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 320ms
14:05:17 INFO - TEST-START | test_click.py TestClick.test_css_transforms
14:05:17 INFO - 1499720717310 Marionette DEBUG Closed connection 370
14:05:17 INFO - 1499720717311 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49968
14:05:17 INFO - 1499720717312 Marionette DEBUG Closed connection 371
14:05:17 INFO - 1499720717313 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49969
14:05:17 INFO - 1499720717314 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:17 INFO - 1499720717315 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717317 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717319 Marionette TRACE 372 <- [1,1,null,{"sessionId":"483f4253-895c-4a1c-8e03-b22755083a3f","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717320 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
14:05:17 INFO - 1499720717321 Marionette TRACE 372 <- [1,2,null,{}]
14:05:17 INFO - 1499720717322 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:05:17 INFO - 1499720717322 Marionette TRACE 372 <- [1,3,null,{}]
14:05:17 INFO - 1499720717323 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
14:05:17 INFO - 1499720717323 Marionette TRACE 372 <- [1,4,null,{}]
14:05:17 INFO - 1499720717325 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
14:05:17 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499720717328 Marionette TRACE 372 <- [1,5,null,{"value":null}]
14:05:17 INFO - 1499720717328 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
14:05:17 INFO - 1499720717330 Marionette TRACE 372 <- [1,6,null,{}]
14:05:17 INFO - 1499720717331 Marionette DEBUG Closed connection 372
14:05:17 INFO - 1499720717332 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49970
14:05:17 INFO - 1499720717332 Marionette DEBUG Closed connection 373
14:05:17 INFO - 1499720717333 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49971
14:05:17 INFO - 1499720717334 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:17 INFO - 1499720717334 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717336 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717339 Marionette TRACE 374 <- [1,1,null,{"sessionId":"c027bd46-a47c-457d-b53b-b4080e45317e","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717340 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
14:05:17 INFO - 1499720717341 Marionette TRACE 374 <- [1,2,null,{}]
14:05:17 INFO - 1499720717342 Marionette DEBUG Closed connection 374
14:05:17 INFO - 1499720717343 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49972
14:05:17 INFO - 1499720717344 Marionette DEBUG Closed connection 375
14:05:17 INFO - 1499720717345 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49973
14:05:17 INFO - 1499720717346 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:05:17 INFO - 1499720717346 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717349 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717359 Marionette TRACE 376 <- [1,1,null,{"sessionId":"07b32a2f-5b66-4aee-b95e-5603c749e772","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717360 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
14:05:17 INFO - 1499720717362 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"
14:05:17 INFO - 1499720717364 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"
14:05:17 INFO - 1499720717364 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"
14:05:17 INFO - 1499720717370 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"
14:05:17 INFO - 1499720717372 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"
14:05:17 INFO - 1499720717375 Marionette TRACE 376 <- [1,2,null,{}]
14:05:17 INFO - 1499720717385 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
14:05:17 INFO - 1499720717396 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b6d9a2e5-7acb-4aba-ba12-4c0fc5805755","ELEMENT":"b6d9a2e5-7acb-4aba-ba12-4c0fc5805755"}}]
14:05:17 INFO - 1499720717398 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b6d9a2e5-7acb-4aba-ba12-4c0fc5805755"}]
14:05:17 INFO - 1499720717632 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:05:17 INFO - 1499720717633 Marionette TRACE 376 <- [1,4,null,{}]
14:05:17 INFO - 1499720717635 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
14:05:17 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499720717637 Marionette TRACE 376 <- [1,5,null,{"value":null}]
14:05:17 INFO - 1499720717638 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
14:05:17 INFO - 1499720717639 Marionette TRACE 376 <- [1,6,null,{}]
14:05:17 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 330ms
14:05:17 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
14:05:17 INFO - 1499720717641 Marionette DEBUG Closed connection 376
14:05:17 INFO - 1499720717642 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49974
14:05:17 INFO - 1499720717643 Marionette DEBUG Closed connection 377
14:05:17 INFO - 1499720717644 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49975
14:05:17 INFO - 1499720717645 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:17 INFO - 1499720717645 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717647 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717650 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e2a6c6b8-2365-40e4-b519-9f8a3abb2633","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717651 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
14:05:17 INFO - 1499720717652 Marionette TRACE 378 <- [1,2,null,{}]
14:05:17 INFO - 1499720717652 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:05:17 INFO - 1499720717653 Marionette TRACE 378 <- [1,3,null,{}]
14:05:17 INFO - 1499720717654 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
14:05:17 INFO - 1499720717654 Marionette TRACE 378 <- [1,4,null,{}]
14:05:17 INFO - 1499720717656 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
14:05:17 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499720717658 Marionette TRACE 378 <- [1,5,null,{"value":null}]
14:05:17 INFO - 1499720717659 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
14:05:17 INFO - 1499720717660 Marionette TRACE 378 <- [1,6,null,{}]
14:05:17 INFO - 1499720717661 Marionette DEBUG Closed connection 378
14:05:17 INFO - 1499720717662 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49976
14:05:17 INFO - 1499720717662 Marionette DEBUG Closed connection 379
14:05:17 INFO - 1499720717663 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49977
14:05:17 INFO - 1499720717664 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:17 INFO - 1499720717664 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717666 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717669 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a7d08298-2c20-4134-aeac-7d1f8b720801","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717670 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
14:05:17 INFO - 1499720717671 Marionette TRACE 380 <- [1,2,null,{}]
14:05:17 INFO - 1499720717672 Marionette DEBUG Closed connection 380
14:05:17 INFO - 1499720717673 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49978
14:05:17 INFO - 1499720717674 Marionette DEBUG Closed connection 381
14:05:17 INFO - 1499720717674 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49979
14:05:17 INFO - 1499720717675 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:05:17 INFO - 1499720717676 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717678 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717681 Marionette TRACE 382 <- [1,1,null,{"sessionId":"783994b1-e2ef-4496-a017-8d182129a16d","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717682 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
14:05:17 INFO - 1499720717683 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"
14:05:17 INFO - 1499720717685 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"
14:05:17 INFO - 1499720717685 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"
14:05:17 INFO - 1499720717691 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"
14:05:17 INFO - 1499720717694 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"
14:05:17 INFO - 1499720717699 Marionette TRACE 382 <- [1,2,null,{}]
14:05:17 INFO - 1499720717702 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
14:05:17 INFO - 1499720717706 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"62205236-97fe-4bcc-9d4d-03eb87e149a3","ELEMENT":"62205236-97fe-4bcc-9d4d-03eb87e149a3"}}]
14:05:17 INFO - 1499720717707 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"62205236-97fe-4bcc-9d4d-03eb87e149a3"}]
14:05:17 INFO - 1499720717946 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:05:17 INFO - 1499720717947 Marionette TRACE 382 <- [1,4,null,{}]
14:05:17 INFO - 1499720717949 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
14:05:17 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499720717951 Marionette TRACE 382 <- [1,5,null,{"value":null}]
14:05:17 INFO - 1499720717952 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
14:05:17 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 313ms
14:05:17 INFO - 1499720717953 Marionette TRACE 382 <- [1,6,null,{}]
14:05:17 INFO - TEST-START | test_click.py TestClick.test_input_file
14:05:17 INFO - 1499720717955 Marionette DEBUG Closed connection 382
14:05:17 INFO - 1499720717957 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49980
14:05:17 INFO - 1499720717957 Marionette DEBUG Closed connection 383
14:05:17 INFO - 1499720717958 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49981
14:05:17 INFO - 1499720717959 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:17 INFO - 1499720717960 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717962 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717964 Marionette TRACE 384 <- [1,1,null,{"sessionId":"8a283f9a-f461-4590-a1d7-f978639ad80c","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717965 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
14:05:17 INFO - 1499720717966 Marionette TRACE 384 <- [1,2,null,{}]
14:05:17 INFO - 1499720717967 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:05:17 INFO - 1499720717967 Marionette TRACE 384 <- [1,3,null,{}]
14:05:17 INFO - 1499720717968 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
14:05:17 INFO - 1499720717968 Marionette TRACE 384 <- [1,4,null,{}]
14:05:17 INFO - 1499720717970 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
14:05:17 INFO - TEST-START: test_click.py TestClick.test_input_file1499720717972 Marionette TRACE 384 <- [1,5,null,{"value":null}]
14:05:17 INFO - 1499720717973 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
14:05:17 INFO - 1499720717974 Marionette TRACE 384 <- [1,6,null,{}]
14:05:17 INFO - 1499720717975 Marionette DEBUG Closed connection 384
14:05:17 INFO - 1499720717976 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49982
14:05:17 INFO - 1499720717977 Marionette DEBUG Closed connection 385
14:05:17 INFO - 1499720717977 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49983
14:05:17 INFO - 1499720717978 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:17 INFO - 1499720717979 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717981 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717983 Marionette TRACE 386 <- [1,1,null,{"sessionId":"3e1674e7-c198-4317-975f-38d81bfd683c","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717984 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
14:05:17 INFO - 1499720717985 Marionette TRACE 386 <- [1,2,null,{}]
14:05:17 INFO - 1499720717987 Marionette DEBUG Closed connection 386
14:05:17 INFO - 1499720717987 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49984
14:05:17 INFO - 1499720717988 Marionette DEBUG Closed connection 387
14:05:17 INFO - 1499720717989 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49985
14:05:17 INFO - 1499720717990 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:05:17 INFO - 1499720717990 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:17 INFO - 1499720717992 Marionette DEBUG Register listener.js for window 4294967297
14:05:17 INFO - 1499720717995 Marionette TRACE 388 <- [1,1,null,{"sessionId":"67540eda-9ef9-494b-a669-f91d91b14907","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:17 INFO - 1499720717996 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
14:05:18 INFO - 1499720717997 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"
14:05:18 INFO - 1499720718000 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"
14:05:18 INFO - 1499720718000 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"
14:05:18 INFO - 1499720718007 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:05:18 INFO - 1499720718014 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:05:18 INFO - 1499720718020 Marionette TRACE 388 <- [1,2,null,{}]
14:05:18 INFO - 1499720718023 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
14:05:18 INFO - 1499720718025 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84264667-71ee-4967-9ceb-3d87888b51b9","ELEMENT":"84264667-71ee-4967-9ceb-3d87888b51b9"}}]
14:05:18 INFO - 1499720718030 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"84264667-71ee-4967-9ceb-3d87888b51b9"}]
14:05:18 INFO - 1499720718054 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
14:05:18 INFO - 1499720718056 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
14:05:18 INFO - TEST-END: test_click.py TestClick.test_input_file1499720718059 Marionette TRACE 388 <- [1,5,null,{"value":null}]
14:05:18 INFO - 1499720718059 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
14:05:18 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 106ms
14:05:18 INFO - TEST-START | test_click.py TestClick.test_obscured_element
14:05:18 INFO - 1499720718061 Marionette TRACE 388 <- [1,6,null,{}]
14:05:18 INFO - 1499720718062 Marionette DEBUG Closed connection 388
14:05:18 INFO - 1499720718063 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49986
14:05:18 INFO - 1499720718064 Marionette DEBUG Closed connection 389
14:05:18 INFO - 1499720718064 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49987
14:05:18 INFO - 1499720718066 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:18 INFO - 1499720718066 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:18 INFO - 1499720718068 Marionette DEBUG Register listener.js for window 4294967297
14:05:18 INFO - 1499720718071 Marionette TRACE 390 <- [1,1,null,{"sessionId":"707a21a9-9b7d-4e00-a95f-e6465f35faa6","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:18 INFO - 1499720718072 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
14:05:18 INFO - 1499720718072 Marionette TRACE 390 <- [1,2,null,{}]
14:05:18 INFO - 1499720718073 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:05:18 INFO - 1499720718073 Marionette TRACE 390 <- [1,3,null,{}]
14:05:18 INFO - 1499720718074 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
14:05:18 INFO - 1499720718074 Marionette TRACE 390 <- [1,4,null,{}]
14:05:18 INFO - 1499720718076 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
14:05:18 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499720718079 Marionette TRACE 390 <- [1,5,null,{"value":null}]
14:05:18 INFO - 1499720718080 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
14:05:18 INFO - 1499720718081 Marionette TRACE 390 <- [1,6,null,{}]
14:05:18 INFO - 1499720718082 Marionette DEBUG Closed connection 390
14:05:18 INFO - 1499720718083 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49988
14:05:18 INFO - 1499720718083 Marionette DEBUG Closed connection 391
14:05:18 INFO - 1499720718084 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49989
14:05:18 INFO - 1499720718085 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:18 INFO - 1499720718085 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:18 INFO - 1499720718087 Marionette DEBUG Register listener.js for window 4294967297
14:05:18 INFO - 1499720718090 Marionette TRACE 392 <- [1,1,null,{"sessionId":"dd4db6b5-dbf4-45f7-9c45-4318db82c341","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:18 INFO - 1499720718091 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
14:05:18 INFO - 1499720718092 Marionette TRACE 392 <- [1,2,null,{}]
14:05:18 INFO - 1499720718093 Marionette DEBUG Closed connection 392
14:05:18 INFO - 1499720718094 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49990
14:05:18 INFO - 1499720718094 Marionette DEBUG Closed connection 393
14:05:18 INFO - 1499720718095 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49991
14:05:18 INFO - 1499720718096 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:05:18 INFO - 1499720718097 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:18 INFO - 1499720718098 Marionette DEBUG Register listener.js for window 4294967297
14:05:18 INFO - 1499720718101 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a490c5c4-7e86-44a3-91de-37cf1892b170","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:18 INFO - 1499720718102 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
14:05:18 INFO - 1499720718104 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:05:18 INFO - 1499720718106 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:05:18 INFO - 1499720718106 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:05:18 INFO - 1499720718114 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"
14:05:18 INFO - 1499720718115 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"
14:05:18 INFO - 1499720718120 Marionette TRACE 394 <- [1,2,null,{}]
14:05:18 INFO - 1499720718123 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
14:05:18 INFO - 1499720718125 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9e7355bd-8b97-45b2-bf8c-020584dba116","ELEMENT":"9e7355bd-8b97-45b2-bf8c-020584dba116"}}]
14:05:18 INFO - 1499720718130 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
14:05:18 INFO - 1499720718132 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"64334434-48ce-44a6-bd56-19bf4fc462f1","ELEMENT":"64334434-48ce-44a6-bd56-19bf4fc462f1"}}]
14:05:18 INFO - 1499720718133 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9e7355bd-8b97-45b2-bf8c-020584dba116"}]
14:05:18 INFO - 1499720718373 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:05:18 INFO - 1499720718374 Marionette TRACE 394 <- [1,5,null,{}]
14:05:18 INFO - 1499720718375 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"64334434-48ce-44a6-bd56-19bf4fc462f1"}]
14:05:18 INFO - 1499720718388 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
14:05:18 INFO - 1499720718390 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
14:05:18 INFO - 1499720718392 Marionette TRACE 394 <- [1,7,null,{"value":false}]
14:05:18 INFO - 1499720718394 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
14:05:18 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499720718396 Marionette TRACE 394 <- [1,8,null,{"value":null}]
14:05:18 INFO - 1499720718397 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
14:05:18 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
14:05:18 INFO - 1499720718398 Marionette TRACE 394 <- [1,9,null,{}]
14:05:18 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
14:05:18 INFO - 1499720718399 Marionette DEBUG Closed connection 394
14:05:18 INFO - 1499720718401 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49992
14:05:18 INFO - 1499720718401 Marionette DEBUG Closed connection 395
14:05:18 INFO - 1499720718402 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49993
14:05:18 INFO - 1499720718403 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:18 INFO - 1499720718404 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:18 INFO - 1499720718406 Marionette DEBUG Register listener.js for window 4294967297
14:05:18 INFO - 1499720718409 Marionette TRACE 396 <- [1,1,null,{"sessionId":"cabad62a-b69b-47af-9eb5-05abb7976aee","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:18 INFO - 1499720718410 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
14:05:18 INFO - 1499720718410 Marionette TRACE 396 <- [1,2,null,{}]
14:05:18 INFO - 1499720718411 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:05:18 INFO - 1499720718412 Marionette TRACE 396 <- [1,3,null,{}]
14:05:18 INFO - 1499720718412 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
14:05:18 INFO - 1499720718413 Marionette TRACE 396 <- [1,4,null,{}]
14:05:18 INFO - 1499720718414 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
14:05:18 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499720718417 Marionette TRACE 396 <- [1,5,null,{"value":null}]
14:05:18 INFO - 1499720718417 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
14:05:18 INFO - 1499720718418 Marionette TRACE 396 <- [1,6,null,{}]
14:05:18 INFO - 1499720718420 Marionette DEBUG Closed connection 396
14:05:18 INFO - 1499720718420 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49994
14:05:18 INFO - 1499720718421 Marionette DEBUG Closed connection 397
14:05:18 INFO - 1499720718422 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49995
14:05:18 INFO - 1499720718423 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:05:18 INFO - 1499720718423 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:18 INFO - 1499720718425 Marionette DEBUG Register listener.js for window 4294967297
14:05:18 INFO - 1499720718428 Marionette TRACE 398 <- [1,1,null,{"sessionId":"539dc083-dc65-429b-aafb-62cb278a0380","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:18 INFO - 1499720718429 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
14:05:18 INFO - 1499720718430 Marionette TRACE 398 <- [1,2,null,{}]
14:05:18 INFO - 1499720718431 Marionette DEBUG Closed connection 398
14:05:18 INFO - 1499720718432 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49996
14:05:18 INFO - 1499720718432 Marionette DEBUG Closed connection 399
14:05:18 INFO - 1499720718433 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49997
14:05:18 INFO - 1499720718434 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:05:18 INFO - 1499720718434 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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}
14:05:18 INFO - 1499720718436 Marionette DEBUG Register listener.js for window 4294967297
14:05:18 INFO - 1499720718439 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8b580381-38cf-40ef-8f45-e4d882855913","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":2832,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpv7xxtc.mozrunner","moz:accessibilityChecks":false}}]
14:05:18 INFO - 1499720718440 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
14:05:18 INFO - 1499720718442 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"
14:05:18 INFO - 1499720718444 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"
14:05:18 INFO - 1499720718444 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"
14:05:18 INFO - 1499720718451 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"
14:05:18 INFO - 1499720718453 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"
14:05:18 INFO - 1499720718455 Marionette TRACE 400 <- [1,2,null,{}]
14:05:18 INFO - 1499720718458 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
14:05:18 INFO - 1499720718464 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e42491fe-98f0-47cf-9005-2dbe7be403ff","ELEMENT":"e42491fe-98f0-47cf-9005-2dbe7be403ff"}}]
14:05:18 INFO - 1499720718465 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"e42491fe-98f0-47cf-9005-2dbe7be403ff"}]
14:05:18 INFO - 1499720718486 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
14:05:18 INFO - 1499720718487 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"e42491fe-98f0-47cf-9005-2dbe7be403ff"}]
14:05:18 INFO - 1499720718501 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element