could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
09:56:42 INFO - 1499273802534 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}]
09:56:42 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499273802537 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:56:42 INFO - 1499273802538 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:56:42 INFO - 1499273802540 Marionette TRACE 352 <- [1,6,null,{}]
09:56:42 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 158ms
09:56:42 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:56:42 INFO - 1499273802542 Marionette DEBUG Closed connection 352
09:56:42 INFO - 1499273802543 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49814
09:56:42 INFO - 1499273802544 Marionette DEBUG Closed connection 353
09:56:42 INFO - 1499273802545 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49815
09:56:42 INFO - 1499273802546 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:42 INFO - 1499273802547 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:42 INFO - 1499273802550 Marionette DEBUG Register listener.js for window 4294967297
09:56:42 INFO - 1499273802553 Marionette TRACE 354 <- [1,1,null,{"sessionId":"c001b9f9-f820-4fe4-b615-50f2d0111f49","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:42 INFO - 1499273802555 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:56:42 INFO - 1499273802555 Marionette TRACE 354 <- [1,2,null,{}]
09:56:42 INFO - 1499273802556 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:42 INFO - 1499273802557 Marionette TRACE 354 <- [1,3,null,{}]
09:56:42 INFO - 1499273802558 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:42 INFO - 1499273802558 Marionette TRACE 354 <- [1,4,null,{}]
09:56:42 INFO - 1499273802560 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}]
09:56:42 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499273802563 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:56:42 INFO - 1499273802564 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:56:42 INFO - 1499273802566 Marionette TRACE 354 <- [1,6,null,{}]
09:56:42 INFO - 1499273802568 Marionette DEBUG Closed connection 354
09:56:42 INFO - 1499273802568 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49816
09:56:42 INFO - 1499273802570 Marionette DEBUG Closed connection 355
09:56:42 INFO - 1499273802570 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49817
09:56:42 INFO - 1499273802571 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:42 INFO - 1499273802572 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:42 INFO - 1499273802575 Marionette DEBUG Register listener.js for window 4294967297
09:56:42 INFO - 1499273802579 Marionette TRACE 356 <- [1,1,null,{"sessionId":"c3c1d0ed-510e-4a9f-9d02-08e7a2d4b54e","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:42 INFO - 1499273802580 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:56:42 INFO - 1499273802582 Marionette TRACE 356 <- [1,2,null,{}]
09:56:42 INFO - 1499273802584 Marionette DEBUG Closed connection 356
09:56:42 INFO - 1499273802585 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49818
09:56:42 INFO - 1499273802586 Marionette DEBUG Closed connection 357
09:56:42 INFO - 1499273802586 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49819
09:56:42 INFO - 1499273802588 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:42 INFO - 1499273802588 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:42 INFO - 1499273802592 Marionette DEBUG Register listener.js for window 4294967297
09:56:42 INFO - 1499273802596 Marionette TRACE 358 <- [1,1,null,{"sessionId":"0e84d2ec-fd8f-4564-883d-67ace7149a8f","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:42 INFO - 1499273802597 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49367/clicks.html"}]
09:56:42 INFO - 1499273802599 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/hidden.html"
09:56:42 INFO - 1499273802612 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/hidden.html"
09:56:42 INFO - 1499273802613 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/hidden.html"
09:56:42 INFO - 1499273802621 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49367/clicks.html"
09:56:42 INFO - 1499273802628 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49367/clicks.html"
09:56:42 INFO - 1499273802630 Marionette TRACE 358 <- [1,2,null,{}]
09:56:42 INFO - 1499273802635 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:56:42 INFO - 1499273802639 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05ddb1e9-acca-4052-8def-248e35b5b602","ELEMENT":"05ddb1e9-acca-4052-8def-248e35b5b602"}}]
09:56:42 INFO - 1499273802645 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"05ddb1e9-acca-4052-8def-248e35b5b602"}]
09:56:42 INFO - 1499273802686 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/clicks.html"
09:56:42 INFO - 1499273802700 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/clicks.html"
09:56:42 INFO - 1499273802700 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/clicks.html"
09:56:42 INFO - 1499273802714 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49367/test.html"
09:56:42 INFO - 1499273802718 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49367/test.html"
09:56:42 INFO - 1499273802719 Marionette TRACE 358 <- [1,4,null,{}]
09:56:42 INFO - 1499273802728 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:56:42 INFO - 1499273802735 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"be9b4418-0b72-4a1e-b9db-9254aa8a3c2a","ELEMENT":"be9b4418-0b72-4a1e-b9db-9254aa8a3c2a"}}]
09:56:42 INFO - 1499273802736 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:56:42 INFO - 1499273802737 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:56:42 INFO - 1499273802740 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}]
09:56:42 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499273802746 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:56:42 INFO - 1499273802747 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:56:42 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 209ms
09:56:42 INFO - 1499273802749 Marionette TRACE 358 <- [1,8,null,{}]
09:56:42 INFO - TEST-START | test_click.py TestClick.test_container_element
09:56:42 INFO - 1499273802751 Marionette DEBUG Closed connection 358
09:56:42 INFO - 1499273802752 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49822
09:56:42 INFO - 1499273802753 Marionette DEBUG Closed connection 359
09:56:42 INFO - 1499273802754 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49823
09:56:42 INFO - 1499273802755 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:42 INFO - 1499273802756 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:42 INFO - 1499273802759 Marionette DEBUG Register listener.js for window 4294967297
09:56:42 INFO - 1499273802764 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3783ca23-5015-4320-b397-47cc1c1d4e69","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:42 INFO - 1499273802765 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:56:42 INFO - 1499273802766 Marionette TRACE 360 <- [1,2,null,{}]
09:56:42 INFO - 1499273802767 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:42 INFO - 1499273802767 Marionette TRACE 360 <- [1,3,null,{}]
09:56:42 INFO - 1499273802768 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:42 INFO - 1499273802769 Marionette TRACE 360 <- [1,4,null,{}]
09:56:42 INFO - 1499273802771 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}]
09:56:42 INFO - TEST-START: test_click.py TestClick.test_container_element1499273802774 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:56:42 INFO - 1499273802775 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:56:42 INFO - 1499273802777 Marionette TRACE 360 <- [1,6,null,{}]
09:56:42 INFO - 1499273802779 Marionette DEBUG Closed connection 360
09:56:42 INFO - 1499273802780 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49824
09:56:42 INFO - 1499273802781 Marionette DEBUG Closed connection 361
09:56:42 INFO - 1499273802781 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49825
09:56:42 INFO - 1499273802783 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:42 INFO - 1499273802783 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:42 INFO - 1499273802787 Marionette DEBUG Register listener.js for window 4294967297
09:56:42 INFO - 1499273802790 Marionette TRACE 362 <- [1,1,null,{"sessionId":"565ccb88-ecc6-4748-97f0-aa40ef22db44","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:42 INFO - 1499273802792 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:56:42 INFO - 1499273802793 Marionette TRACE 362 <- [1,2,null,{}]
09:56:42 INFO - 1499273802795 Marionette DEBUG Closed connection 362
09:56:42 INFO - 1499273802796 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49826
09:56:42 INFO - 1499273802797 Marionette DEBUG Closed connection 363
09:56:42 INFO - 1499273802797 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49827
09:56:42 INFO - 1499273802799 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:42 INFO - 1499273802799 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:42 INFO - 1499273802803 Marionette DEBUG Register listener.js for window 4294967297
09:56:42 INFO - 1499273802806 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5f476e10-090f-47e5-826e-f7b7c90e0f10","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:42 INFO - 1499273802808 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"}]
09:56:42 INFO - 1499273802810 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49367/test.html"
09:56:42 INFO - 1499273802812 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49367/test.html"
09:56:42 INFO - 1499273802813 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49367/test.html"
09:56:42 INFO - 1499273802829 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"
09:56:42 INFO - 1499273802831 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"
09:56:42 INFO - 1499273802838 Marionette TRACE 364 <- [1,2,null,{}]
09:56:42 INFO - 1499273802849 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:56:42 INFO - 1499273802854 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9ad2d3a6-cbab-4ad7-a12f-dcf63dfe2c43","ELEMENT":"9ad2d3a6-cbab-4ad7-a12f-dcf63dfe2c43"}}]
09:56:42 INFO - 1499273802855 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"9ad2d3a6-cbab-4ad7-a12f-dcf63dfe2c43"}]
09:56:43 INFO - 1499273803103 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:43 INFO - 1499273803104 Marionette TRACE 364 <- [1,4,null,{}]
09:56:43 INFO - 1499273803105 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"9ad2d3a6-cbab-4ad7-a12f-dcf63dfe2c43","name":"selected"}]
09:56:43 INFO - 1499273803111 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:56:43 INFO - 1499273803113 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}]
09:56:43 INFO - TEST-END: test_click.py TestClick.test_container_element1499273803117 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:56:43 INFO - 1499273803118 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:56:43 INFO - 1499273803120 Marionette TRACE 364 <- [1,7,null,{}]
09:56:43 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 370ms
09:56:43 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:56:43 INFO - 1499273803122 Marionette DEBUG Closed connection 364
09:56:43 INFO - 1499273803123 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49828
09:56:43 INFO - 1499273803124 Marionette DEBUG Closed connection 365
09:56:43 INFO - 1499273803125 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49829
09:56:43 INFO - 1499273803126 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:43 INFO - 1499273803127 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803131 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803134 Marionette TRACE 366 <- [1,1,null,{"sessionId":"aa7786a1-4200-4872-88c4-1395b25f6145","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803136 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:56:43 INFO - 1499273803136 Marionette TRACE 366 <- [1,2,null,{}]
09:56:43 INFO - 1499273803137 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:43 INFO - 1499273803138 Marionette TRACE 366 <- [1,3,null,{}]
09:56:43 INFO - 1499273803139 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:43 INFO - 1499273803139 Marionette TRACE 366 <- [1,4,null,{}]
09:56:43 INFO - 1499273803141 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}]
09:56:43 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499273803144 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:56:43 INFO - 1499273803145 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:56:43 INFO - 1499273803147 Marionette TRACE 366 <- [1,6,null,{}]
09:56:43 INFO - 1499273803149 Marionette DEBUG Closed connection 366
09:56:43 INFO - 1499273803150 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49830
09:56:43 INFO - 1499273803151 Marionette DEBUG Closed connection 367
09:56:43 INFO - 1499273803152 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49831
09:56:43 INFO - 1499273803153 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:43 INFO - 1499273803153 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803156 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803160 Marionette TRACE 368 <- [1,1,null,{"sessionId":"4a602ac8-c355-44f0-91b7-d11ac8c2d6a0","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803161 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:56:43 INFO - 1499273803163 Marionette TRACE 368 <- [1,2,null,{}]
09:56:43 INFO - 1499273803165 Marionette DEBUG Closed connection 368
09:56:43 INFO - 1499273803165 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49832
09:56:43 INFO - 1499273803166 Marionette DEBUG Closed connection 369
09:56:43 INFO - 1499273803167 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49833
09:56:43 INFO - 1499273803168 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:43 INFO - 1499273803169 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803172 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803176 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b086894c-3e1e-4cda-9335-e33ce7a4e1ec","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803177 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"}]
09:56:43 INFO - 1499273803179 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"
09:56:43 INFO - 1499273803181 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"
09:56:43 INFO - 1499273803182 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"
09:56:43 INFO - 1499273803189 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"
09:56:43 INFO - 1499273803193 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"
09:56:43 INFO - 1499273803201 Marionette TRACE 370 <- [1,2,null,{}]
09:56:43 INFO - 1499273803211 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:56:43 INFO - 1499273803215 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"42809846-c143-40d8-8c46-4ecd2953f71f","ELEMENT":"42809846-c143-40d8-8c46-4ecd2953f71f"}}]
09:56:43 INFO - 1499273803216 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"42809846-c143-40d8-8c46-4ecd2953f71f"}]
09:56:43 INFO - 1499273803468 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:43 INFO - 1499273803469 Marionette TRACE 370 <- [1,4,null,{}]
09:56:43 INFO - 1499273803470 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"42809846-c143-40d8-8c46-4ecd2953f71f","name":"selected"}]
09:56:43 INFO - 1499273803476 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:56:43 INFO - 1499273803478 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}]
09:56:43 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499273803481 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:56:43 INFO - 1499273803483 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:56:43 INFO - 1499273803484 Marionette TRACE 370 <- [1,7,null,{}]
09:56:43 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 363ms
09:56:43 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:56:43 INFO - 1499273803486 Marionette DEBUG Closed connection 370
09:56:43 INFO - 1499273803487 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49834
09:56:43 INFO - 1499273803488 Marionette DEBUG Closed connection 371
09:56:43 INFO - 1499273803489 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49835
09:56:43 INFO - 1499273803490 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:43 INFO - 1499273803491 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803494 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803498 Marionette TRACE 372 <- [1,1,null,{"sessionId":"f250214f-917d-4038-8520-ce9991863c8e","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803499 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:56:43 INFO - 1499273803500 Marionette TRACE 372 <- [1,2,null,{}]
09:56:43 INFO - 1499273803501 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:43 INFO - 1499273803501 Marionette TRACE 372 <- [1,3,null,{}]
09:56:43 INFO - 1499273803502 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:43 INFO - 1499273803503 Marionette TRACE 372 <- [1,4,null,{}]
09:56:43 INFO - 1499273803505 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}]
09:56:43 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499273803508 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:56:43 INFO - 1499273803510 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:56:43 INFO - 1499273803511 Marionette TRACE 372 <- [1,6,null,{}]
09:56:43 INFO - 1499273803513 Marionette DEBUG Closed connection 372
09:56:43 INFO - 1499273803514 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49836
09:56:43 INFO - 1499273803515 Marionette DEBUG Closed connection 373
09:56:43 INFO - 1499273803516 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49837
09:56:43 INFO - 1499273803517 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:43 INFO - 1499273803518 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803521 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803525 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4ec9c7ab-0569-420e-b044-6cf36a764010","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803526 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:56:43 INFO - 1499273803528 Marionette TRACE 374 <- [1,2,null,{}]
09:56:43 INFO - 1499273803530 Marionette DEBUG Closed connection 374
09:56:43 INFO - 1499273803531 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49838
09:56:43 INFO - 1499273803532 Marionette DEBUG Closed connection 375
09:56:43 INFO - 1499273803533 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49839
09:56:43 INFO - 1499273803534 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:43 INFO - 1499273803534 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803538 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803541 Marionette TRACE 376 <- [1,1,null,{"sessionId":"7a6b3117-aa2d-4bbe-ba04-a92f2e85d389","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803543 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"}]
09:56:43 INFO - 1499273803545 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"
09:56:43 INFO - 1499273803547 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"
09:56:43 INFO - 1499273803548 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"
09:56:43 INFO - 1499273803555 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"
09:56:43 INFO - 1499273803558 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"
09:56:43 INFO - 1499273803568 Marionette TRACE 376 <- [1,2,null,{}]
09:56:43 INFO - 1499273803580 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:56:43 INFO - 1499273803583 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"846677c6-d519-42c2-82ed-0a94963173a3","ELEMENT":"846677c6-d519-42c2-82ed-0a94963173a3"}}]
09:56:43 INFO - 1499273803585 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"846677c6-d519-42c2-82ed-0a94963173a3"}]
09:56:43 INFO - 1499273803828 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:43 INFO - 1499273803829 Marionette TRACE 376 <- [1,4,null,{}]
09:56:43 INFO - 1499273803831 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}]
09:56:43 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499273803835 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:56:43 INFO - 1499273803836 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:56:43 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 353ms
09:56:43 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:56:43 INFO - 1499273803838 Marionette TRACE 376 <- [1,6,null,{}]
09:56:43 INFO - 1499273803840 Marionette DEBUG Closed connection 376
09:56:43 INFO - 1499273803841 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49840
09:56:43 INFO - 1499273803842 Marionette DEBUG Closed connection 377
09:56:43 INFO - 1499273803843 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49841
09:56:43 INFO - 1499273803844 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:43 INFO - 1499273803845 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803848 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803852 Marionette TRACE 378 <- [1,1,null,{"sessionId":"52da4b52-0d8c-4438-bb6e-988c4cf059ca","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803853 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:56:43 INFO - 1499273803854 Marionette TRACE 378 <- [1,2,null,{}]
09:56:43 INFO - 1499273803855 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:43 INFO - 1499273803856 Marionette TRACE 378 <- [1,3,null,{}]
09:56:43 INFO - 1499273803857 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:43 INFO - 1499273803857 Marionette TRACE 378 <- [1,4,null,{}]
09:56:43 INFO - 1499273803859 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}]
09:56:43 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499273803863 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:56:43 INFO - 1499273803864 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:56:43 INFO - 1499273803866 Marionette TRACE 378 <- [1,6,null,{}]
09:56:43 INFO - 1499273803867 Marionette DEBUG Closed connection 378
09:56:43 INFO - 1499273803868 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49842
09:56:43 INFO - 1499273803869 Marionette DEBUG Closed connection 379
09:56:43 INFO - 1499273803870 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49843
09:56:43 INFO - 1499273803871 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:43 INFO - 1499273803872 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803875 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803889 Marionette TRACE 380 <- [1,1,null,{"sessionId":"9aa25870-0cc1-4753-88d2-a9f585971458","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803891 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:56:43 INFO - 1499273803892 Marionette TRACE 380 <- [1,2,null,{}]
09:56:43 INFO - 1499273803894 Marionette DEBUG Closed connection 380
09:56:43 INFO - 1499273803895 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49844
09:56:43 INFO - 1499273803896 Marionette DEBUG Closed connection 381
09:56:43 INFO - 1499273803897 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49845
09:56:43 INFO - 1499273803898 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:43 INFO - 1499273803899 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:43 INFO - 1499273803902 Marionette DEBUG Register listener.js for window 4294967297
09:56:43 INFO - 1499273803906 Marionette TRACE 382 <- [1,1,null,{"sessionId":"f8381279-6220-4cb1-b43b-99330abbe991","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:43 INFO - 1499273803908 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"}]
09:56:43 INFO - 1499273803911 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"
09:56:43 INFO - 1499273803913 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"
09:56:43 INFO - 1499273803914 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"
09:56:43 INFO - 1499273803923 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"
09:56:43 INFO - 1499273803928 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"
09:56:43 INFO - 1499273803934 Marionette TRACE 382 <- [1,2,null,{}]
09:56:43 INFO - 1499273803938 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:56:43 INFO - 1499273803946 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f9fa942-fdcf-4817-af30-8ad66e4cdab9","ELEMENT":"3f9fa942-fdcf-4817-af30-8ad66e4cdab9"}}]
09:56:43 INFO - 1499273803948 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"3f9fa942-fdcf-4817-af30-8ad66e4cdab9"}]
09:56:44 INFO - 1499273804210 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:44 INFO - 1499273804211 Marionette TRACE 382 <- [1,4,null,{}]
09:56:44 INFO - 1499273804213 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}]
09:56:44 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499273804216 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:56:44 INFO - 1499273804217 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:56:44 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 380ms
09:56:44 INFO - TEST-START | test_click.py TestClick.test_input_file
09:56:44 INFO - 1499273804219 Marionette TRACE 382 <- [1,6,null,{}]
09:56:44 INFO - 1499273804221 Marionette DEBUG Closed connection 382
09:56:44 INFO - 1499273804222 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49846
09:56:44 INFO - 1499273804222 Marionette DEBUG Closed connection 383
09:56:44 INFO - 1499273804223 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49847
09:56:44 INFO - 1499273804225 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:44 INFO - 1499273804225 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804228 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804232 Marionette TRACE 384 <- [1,1,null,{"sessionId":"8c434214-9524-43f0-8c4b-28be4625394f","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804233 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:56:44 INFO - 1499273804234 Marionette TRACE 384 <- [1,2,null,{}]
09:56:44 INFO - 1499273804235 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:44 INFO - 1499273804236 Marionette TRACE 384 <- [1,3,null,{}]
09:56:44 INFO - 1499273804237 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:44 INFO - 1499273804237 Marionette TRACE 384 <- [1,4,null,{}]
09:56:44 INFO - 1499273804239 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}]
09:56:44 INFO - TEST-START: test_click.py TestClick.test_input_file1499273804242 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:56:44 INFO - 1499273804243 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:56:44 INFO - 1499273804245 Marionette TRACE 384 <- [1,6,null,{}]
09:56:44 INFO - 1499273804247 Marionette DEBUG Closed connection 384
09:56:44 INFO - 1499273804248 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49848
09:56:44 INFO - 1499273804249 Marionette DEBUG Closed connection 385
09:56:44 INFO - 1499273804249 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49849
09:56:44 INFO - 1499273804251 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:44 INFO - 1499273804251 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804255 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804258 Marionette TRACE 386 <- [1,1,null,{"sessionId":"20806650-af79-43ad-a58b-f4e8aa8c8e5b","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804259 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:56:44 INFO - 1499273804261 Marionette TRACE 386 <- [1,2,null,{}]
09:56:44 INFO - 1499273804263 Marionette DEBUG Closed connection 386
09:56:44 INFO - 1499273804263 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49850
09:56:44 INFO - 1499273804264 Marionette DEBUG Closed connection 387
09:56:44 INFO - 1499273804265 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49851
09:56:44 INFO - 1499273804266 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:44 INFO - 1499273804267 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804270 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804274 Marionette TRACE 388 <- [1,1,null,{"sessionId":"85150f05-dced-4b1c-91ea-76e3de8ae825","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804275 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:56:44 INFO - 1499273804277 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"
09:56:44 INFO - 1499273804279 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"
09:56:44 INFO - 1499273804280 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"
09:56:44 INFO - 1499273804287 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:44 INFO - 1499273804293 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:44 INFO - 1499273804305 Marionette TRACE 388 <- [1,2,null,{}]
09:56:44 INFO - 1499273804314 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:56:44 INFO - 1499273804318 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c25d545f-3cb3-47ff-abcd-165114b3dbad","ELEMENT":"c25d545f-3cb3-47ff-abcd-165114b3dbad"}}]
09:56:44 INFO - 1499273804319 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"c25d545f-3cb3-47ff-abcd-165114b3dbad"}]
09:56:44 INFO - 1499273804349 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
09:56:44 INFO - 1499273804351 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}]
09:56:44 INFO - TEST-END: test_click.py TestClick.test_input_file1499273804354 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:56:44 INFO - 1499273804356 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:56:44 INFO - 1499273804357 Marionette TRACE 388 <- [1,6,null,{}]
09:56:44 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 138ms
09:56:44 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:56:44 INFO - 1499273804359 Marionette DEBUG Closed connection 388
09:56:44 INFO - 1499273804360 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49852
09:56:44 INFO - 1499273804361 Marionette DEBUG Closed connection 389
09:56:44 INFO - 1499273804362 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49853
09:56:44 INFO - 1499273804363 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:44 INFO - 1499273804364 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804367 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804370 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7895f1ff-8c6a-4d7a-a183-ea581226e88b","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804372 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:56:44 INFO - 1499273804372 Marionette TRACE 390 <- [1,2,null,{}]
09:56:44 INFO - 1499273804373 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:44 INFO - 1499273804374 Marionette TRACE 390 <- [1,3,null,{}]
09:56:44 INFO - 1499273804375 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:44 INFO - 1499273804375 Marionette TRACE 390 <- [1,4,null,{}]
09:56:44 INFO - 1499273804377 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}]
09:56:44 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499273804381 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:56:44 INFO - 1499273804382 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:56:44 INFO - 1499273804384 Marionette TRACE 390 <- [1,6,null,{}]
09:56:44 INFO - 1499273804385 Marionette DEBUG Closed connection 390
09:56:44 INFO - 1499273804386 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49854
09:56:44 INFO - 1499273804387 Marionette DEBUG Closed connection 391
09:56:44 INFO - 1499273804388 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49855
09:56:44 INFO - 1499273804389 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:44 INFO - 1499273804390 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804393 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804397 Marionette TRACE 392 <- [1,1,null,{"sessionId":"103c0d6c-abd5-425f-b66f-362445692744","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804398 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:56:44 INFO - 1499273804400 Marionette TRACE 392 <- [1,2,null,{}]
09:56:44 INFO - 1499273804402 Marionette DEBUG Closed connection 392
09:56:44 INFO - 1499273804403 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49856
09:56:44 INFO - 1499273804404 Marionette DEBUG Closed connection 393
09:56:44 INFO - 1499273804404 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49857
09:56:44 INFO - 1499273804405 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:44 INFO - 1499273804406 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804410 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804413 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e9ba8d06-bac3-46c3-bb92-a76aa207b312","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804414 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"}]
09:56:44 INFO - 1499273804416 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:44 INFO - 1499273804419 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:44 INFO - 1499273804419 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:56:44 INFO - 1499273804429 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"
09:56:44 INFO - 1499273804431 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"
09:56:44 INFO - 1499273804437 Marionette TRACE 394 <- [1,2,null,{}]
09:56:44 INFO - 1499273804449 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:56:44 INFO - 1499273804453 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d633cc30-4a46-4e48-8be8-768ada4673f9","ELEMENT":"d633cc30-4a46-4e48-8be8-768ada4673f9"}}]
09:56:44 INFO - 1499273804455 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:56:44 INFO - 1499273804458 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d85258e0-06d4-4f61-b8d6-18626408a02f","ELEMENT":"d85258e0-06d4-4f61-b8d6-18626408a02f"}}]
09:56:44 INFO - 1499273804459 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"d633cc30-4a46-4e48-8be8-768ada4673f9"}]
09:56:44 INFO - 1499273804701 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:56:44 INFO - 1499273804702 Marionette TRACE 394 <- [1,5,null,{}]
09:56:44 INFO - 1499273804703 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d85258e0-06d4-4f61-b8d6-18626408a02f"}]
09:56:44 INFO - 1499273804782 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
09:56:44 INFO - 1499273804787 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:56:44 INFO - 1499273804792 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:56:44 INFO - 1499273804795 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}]
09:56:44 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499273804825 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:56:44 INFO - 1499273804831 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:56:44 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 476ms
09:56:44 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:56:44 INFO - 1499273804835 Marionette TRACE 394 <- [1,9,null,{}]
09:56:44 INFO - 1499273804838 Marionette DEBUG Closed connection 394
09:56:44 INFO - 1499273804839 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49858
09:56:44 INFO - 1499273804840 Marionette DEBUG Closed connection 395
09:56:44 INFO - 1499273804842 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49859
09:56:44 INFO - 1499273804851 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:44 INFO - 1499273804855 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804859 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804868 Marionette TRACE 396 <- [1,1,null,{"sessionId":"eec3eb8f-125b-4e62-b471-1e62c3cbf8bc","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804876 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:56:44 INFO - 1499273804878 Marionette TRACE 396 <- [1,2,null,{}]
09:56:44 INFO - 1499273804879 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:56:44 INFO - 1499273804881 Marionette TRACE 396 <- [1,3,null,{}]
09:56:44 INFO - 1499273804882 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:56:44 INFO - 1499273804883 Marionette TRACE 396 <- [1,4,null,{}]
09:56:44 INFO - 1499273804886 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}]
09:56:44 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499273804893 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:56:44 INFO - 1499273804895 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:56:44 INFO - 1499273804897 Marionette TRACE 396 <- [1,6,null,{}]
09:56:44 INFO - 1499273804899 Marionette DEBUG Closed connection 396
09:56:44 INFO - 1499273804900 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49860
09:56:44 INFO - 1499273804901 Marionette DEBUG Closed connection 397
09:56:44 INFO - 1499273804903 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49861
09:56:44 INFO - 1499273804904 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:56:44 INFO - 1499273804905 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804908 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804914 Marionette TRACE 398 <- [1,1,null,{"sessionId":"8915d220-349d-4880-8b51-482e31c0c726","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804917 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:56:44 INFO - 1499273804921 Marionette TRACE 398 <- [1,2,null,{}]
09:56:44 INFO - 1499273804923 Marionette DEBUG Closed connection 398
09:56:44 INFO - 1499273804924 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49862
09:56:44 INFO - 1499273804925 Marionette DEBUG Closed connection 399
09:56:44 INFO - 1499273804926 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49863
09:56:44 INFO - 1499273804927 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:56:44 INFO - 1499273804928 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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}
09:56:44 INFO - 1499273804932 Marionette DEBUG Register listener.js for window 4294967297
09:56:44 INFO - 1499273804936 Marionette TRACE 400 <- [1,1,null,{"sessionId":"908c6337-448f-471f-9e4a-49e9891b45bc","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":4052,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppw8quo.mozrunner","moz:accessibilityChecks":false}}]
09:56:44 INFO - 1499273804938 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"}]
09:56:44 INFO - 1499273804941 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"
09:56:44 INFO - 1499273804943 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"
09:56:44 INFO - 1499273804943 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"
09:56:44 INFO - 1499273804951 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"
09:56:44 INFO - 1499273804954 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"
09:56:44 INFO - 1499273804970 Marionette TRACE 400 <- [1,2,null,{}]
09:56:44 INFO - 1499273804984 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:56:44 INFO - 1499273804988 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a1f4156f-ea11-41aa-aee3-6e8a630feee1","ELEMENT":"a1f4156f-ea11-41aa-aee3-6e8a630feee1"}}]
09:56:44 INFO - 1499273804989 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a1f4156f-ea11-41aa-aee3-6e8a630feee1"}]
09:56:45 INFO - 1499273805019 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:56:45 INFO - 1499273805020 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a1f4156f-ea11-41aa-aee3-6e8a630feee1"}]
09:56:45 INFO - 1499273805039 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element