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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
14:35:26 INFO - 1500500126489 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:35:26 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500500126492 Marionette TRACE 352 <- [1,5,null,{"value":null}]
14:35:26 INFO - 1500500126493 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
14:35:26 INFO - 1500500126495 Marionette TRACE 352 <- [1,6,null,{}]
14:35:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 144ms
14:35:26 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
14:35:26 INFO - 1500500126497 Marionette DEBUG Closed connection 352
14:35:26 INFO - 1500500126498 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49758
14:35:26 INFO - 1500500126499 Marionette DEBUG Closed connection 353
14:35:26 INFO - 1500500126500 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49759
14:35:26 INFO - 1500500126501 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:26 INFO - 1500500126501 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:26 INFO - 1500500126504 Marionette DEBUG Register listener.js for window 15
14:35:26 INFO - 1500500126507 Marionette TRACE 354 <- [1,1,null,{"sessionId":"72e4dc86-4ca4-4969-b7d3-cb3fe998c5e6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:26 INFO - 1500500126510 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
14:35:26 INFO - 1500500126510 Marionette TRACE 354 <- [1,2,null,{}]
14:35:26 INFO - 1500500126511 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:35:26 INFO - 1500500126511 Marionette TRACE 354 <- [1,3,null,{}]
14:35:26 INFO - 1500500126512 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
14:35:26 INFO - 1500500126513 Marionette TRACE 354 <- [1,4,null,{}]
14:35:26 INFO - 1500500126515 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:35:26 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500500126518 Marionette TRACE 354 <- [1,5,null,{"value":null}]
14:35:26 INFO - 1500500126519 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
14:35:26 INFO - 1500500126520 Marionette TRACE 354 <- [1,6,null,{}]
14:35:26 INFO - 1500500126523 Marionette DEBUG Closed connection 354
14:35:26 INFO - 1500500126523 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49760
14:35:26 INFO - 1500500126524 Marionette DEBUG Closed connection 355
14:35:26 INFO - 1500500126525 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49761
14:35:26 INFO - 1500500126526 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:26 INFO - 1500500126526 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:26 INFO - 1500500126529 Marionette DEBUG Register listener.js for window 15
14:35:26 INFO - 1500500126532 Marionette TRACE 356 <- [1,1,null,{"sessionId":"89c609be-47bc-4726-ab7e-c2853dbd5973","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:26 INFO - 1500500126534 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
14:35:26 INFO - 1500500126535 Marionette TRACE 356 <- [1,2,null,{}]
14:35:26 INFO - 1500500126537 Marionette DEBUG Closed connection 356
14:35:26 INFO - 1500500126539 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49762
14:35:26 INFO - 1500500126540 Marionette DEBUG Closed connection 357
14:35:26 INFO - 1500500126541 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49763
14:35:26 INFO - 1500500126542 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:35:26 INFO - 1500500126543 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:26 INFO - 1500500126546 Marionette DEBUG Register listener.js for window 15
14:35:26 INFO - 1500500126549 Marionette TRACE 358 <- [1,1,null,{"sessionId":"3a255cfd-de83-48c3-a0e5-1b146d3bb524","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:26 INFO - 1500500126551 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49331/clicks.html"}]
14:35:26 INFO - 1500500126553 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/hidden.html"
14:35:26 INFO - 1500500126557 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/hidden.html"
14:35:26 INFO - 1500500126558 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/hidden.html"
14:35:26 INFO - 1500500126583 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/clicks.html"
14:35:26 INFO - 1500500126585 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/clicks.html"
14:35:26 INFO - 1500500126591 Marionette TRACE 358 <- [1,2,null,{}]
14:35:26 INFO - 1500500126598 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
14:35:26 INFO - 1500500126600 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5e867310-e2ab-4473-8766-63ba2524e81d","ELEMENT":"5e867310-e2ab-4473-8766-63ba2524e81d"}}]
14:35:26 INFO - 1500500126601 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5e867310-e2ab-4473-8766-63ba2524e81d"}]
14:35:26 INFO - 1500500126652 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/clicks.html"
14:35:26 INFO - 1500500126657 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/clicks.html"
14:35:26 INFO - 1500500126658 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/clicks.html"
14:35:26 INFO - 1500500126682 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/test.html"
14:35:26 INFO - 1500500126683 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/test.html"
14:35:26 INFO - 1500500126686 Marionette TRACE 358 <- [1,4,null,{}]
14:35:26 INFO - 1500500126687 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
14:35:26 INFO - 1500500126690 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"82d61012-f720-43df-85e0-dcf58385a868","ELEMENT":"82d61012-f720-43df-85e0-dcf58385a868"}}]
14:35:26 INFO - 1500500126691 Marionette TRACE 358 -> [0,6,"getTitle",{}]
14:35:26 INFO - 1500500126691 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
14:35:26 INFO - 1500500126699 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:35:26 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500500126702 Marionette TRACE 358 <- [1,7,null,{"value":null}]
14:35:26 INFO - 1500500126703 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
14:35:26 INFO - 1500500126704 Marionette TRACE 358 <- [1,8,null,{}]
14:35:26 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 209ms
14:35:26 INFO - TEST-START | test_click.py TestClick.test_container_element
14:35:26 INFO - 1500500126706 Marionette DEBUG Closed connection 358
14:35:26 INFO - 1500500126707 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49766
14:35:26 INFO - 1500500126708 Marionette DEBUG Closed connection 359
14:35:26 INFO - 1500500126710 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49767
14:35:26 INFO - 1500500126711 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:26 INFO - 1500500126711 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:26 INFO - 1500500126714 Marionette DEBUG Register listener.js for window 15
14:35:26 INFO - 1500500126718 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4f9a4da6-2b97-4b1e-bee0-f6a9f5246aeb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:26 INFO - 1500500126720 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
14:35:26 INFO - 1500500126720 Marionette TRACE 360 <- [1,2,null,{}]
14:35:26 INFO - 1500500126721 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:35:26 INFO - 1500500126722 Marionette TRACE 360 <- [1,3,null,{}]
14:35:26 INFO - 1500500126722 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
14:35:26 INFO - 1500500126723 Marionette TRACE 360 <- [1,4,null,{}]
14:35:26 INFO - 1500500126724 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:35:26 INFO - TEST-START: test_click.py TestClick.test_container_element1500500126728 Marionette TRACE 360 <- [1,5,null,{"value":null}]
14:35:26 INFO - 1500500126729 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
14:35:26 INFO - 1500500126731 Marionette TRACE 360 <- [1,6,null,{}]
14:35:26 INFO - 1500500126733 Marionette DEBUG Closed connection 360
14:35:26 INFO - 1500500126734 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49768
14:35:26 INFO - 1500500126734 Marionette DEBUG Closed connection 361
14:35:26 INFO - 1500500126735 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49769
14:35:26 INFO - 1500500126736 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:26 INFO - 1500500126737 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:26 INFO - 1500500126739 Marionette DEBUG Register listener.js for window 15
14:35:26 INFO - 1500500126744 Marionette TRACE 362 <- [1,1,null,{"sessionId":"b599da21-3c42-4669-a155-7b7fac894c50","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:26 INFO - 1500500126746 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
14:35:26 INFO - 1500500126747 Marionette TRACE 362 <- [1,2,null,{}]
14:35:26 INFO - 1500500126750 Marionette DEBUG Closed connection 362
14:35:26 INFO - 1500500126750 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49770
14:35:26 INFO - 1500500126751 Marionette DEBUG Closed connection 363
14:35:26 INFO - 1500500126752 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49771
14:35:26 INFO - 1500500126753 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:35:26 INFO - 1500500126753 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:26 INFO - 1500500126756 Marionette DEBUG Register listener.js for window 15
14:35:26 INFO - 1500500126761 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5ff2efb1-bf13-4e40-baf1-3f1b437075c9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:26 INFO - 1500500126763 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:35:26 INFO - 1500500126765 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/test.html"
14:35:26 INFO - 1500500126767 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/test.html"
14:35:26 INFO - 1500500126767 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/test.html"
14:35:26 INFO - 1500500126796 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:35:26 INFO - 1500500126798 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:35:26 INFO - 1500500126801 Marionette TRACE 364 <- [1,2,null,{}]
14:35:26 INFO - 1500500126803 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:35:26 INFO - 1500500126805 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b18a84be-18c8-4df0-a219-1ad444597794","ELEMENT":"b18a84be-18c8-4df0-a219-1ad444597794"}}]
14:35:26 INFO - 1500500126806 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b18a84be-18c8-4df0-a219-1ad444597794"}]
14:35:27 INFO - 1500500127059 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:35:27 INFO - 1500500127059 Marionette TRACE 364 <- [1,4,null,{}]
14:35:27 INFO - 1500500127061 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b18a84be-18c8-4df0-a219-1ad444597794","name":"selected"}]
14:35:27 INFO - 1500500127065 Marionette TRACE 364 <- [1,5,null,{"value":true}]
14:35:27 INFO - 1500500127067 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:35:27 INFO - TEST-END: test_click.py TestClick.test_container_element1500500127069 Marionette TRACE 364 <- [1,6,null,{"value":null}]
14:35:27 INFO - 1500500127070 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
14:35:27 INFO - 1500500127071 Marionette TRACE 364 <- [1,7,null,{}]
14:35:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 367ms
14:35:27 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
14:35:27 INFO - 1500500127074 Marionette DEBUG Closed connection 364
14:35:27 INFO - 1500500127075 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49772
14:35:27 INFO - 1500500127076 Marionette DEBUG Closed connection 365
14:35:27 INFO - 1500500127076 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49773
14:35:27 INFO - 1500500127077 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:27 INFO - 1500500127078 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127080 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127083 Marionette TRACE 366 <- [1,1,null,{"sessionId":"d1a7a556-259f-497c-9acf-47d7f70cd5d7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127085 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
14:35:27 INFO - 1500500127086 Marionette TRACE 366 <- [1,2,null,{}]
14:35:27 INFO - 1500500127087 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:35:27 INFO - 1500500127087 Marionette TRACE 366 <- [1,3,null,{}]
14:35:27 INFO - 1500500127089 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
14:35:27 INFO - 1500500127090 Marionette TRACE 366 <- [1,4,null,{}]
14:35:27 INFO - 1500500127092 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:35:27 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500500127095 Marionette TRACE 366 <- [1,5,null,{"value":null}]
14:35:27 INFO - 1500500127096 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
14:35:27 INFO - 1500500127097 Marionette TRACE 366 <- [1,6,null,{}]
14:35:27 INFO - 1500500127099 Marionette DEBUG Closed connection 366
14:35:27 INFO - 1500500127100 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49774
14:35:27 INFO - 1500500127101 Marionette DEBUG Closed connection 367
14:35:27 INFO - 1500500127101 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49775
14:35:27 INFO - 1500500127103 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:27 INFO - 1500500127103 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127106 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127117 Marionette TRACE 368 <- [1,1,null,{"sessionId":"261351f9-dcda-4289-8c08-9780e69acf92","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127119 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
14:35:27 INFO - 1500500127121 Marionette TRACE 368 <- [1,2,null,{}]
14:35:27 INFO - 1500500127123 Marionette DEBUG Closed connection 368
14:35:27 INFO - 1500500127123 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49776
14:35:27 INFO - 1500500127124 Marionette DEBUG Closed connection 369
14:35:27 INFO - 1500500127125 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49777
14:35:27 INFO - 1500500127126 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:35:27 INFO - 1500500127127 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127130 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127133 Marionette TRACE 370 <- [1,1,null,{"sessionId":"66daccfb-73bb-4ae9-8970-3d5f4df59da1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127136 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:35:27 INFO - 1500500127137 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:35:27 INFO - 1500500127139 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:35:27 INFO - 1500500127139 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:35:27 INFO - 1500500127162 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:35:27 INFO - 1500500127164 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:35:27 INFO - 1500500127167 Marionette TRACE 370 <- [1,2,null,{}]
14:35:27 INFO - 1500500127168 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:35:27 INFO - 1500500127171 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40d1e660-48b5-4a7d-904b-19b77503759b","ELEMENT":"40d1e660-48b5-4a7d-904b-19b77503759b"}}]
14:35:27 INFO - 1500500127172 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"40d1e660-48b5-4a7d-904b-19b77503759b"}]
14:35:27 INFO - 1500500127428 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:35:27 INFO - 1500500127428 Marionette TRACE 370 <- [1,4,null,{}]
14:35:27 INFO - 1500500127430 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"40d1e660-48b5-4a7d-904b-19b77503759b","name":"selected"}]
14:35:27 INFO - 1500500127434 Marionette TRACE 370 <- [1,5,null,{"value":true}]
14:35:27 INFO - 1500500127436 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:35:27 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500500127439 Marionette TRACE 370 <- [1,6,null,{"value":null}]
14:35:27 INFO - 1500500127440 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
14:35:27 INFO - 1500500127441 Marionette TRACE 370 <- [1,7,null,{}]
14:35:27 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
14:35:27 INFO - TEST-START | test_click.py TestClick.test_css_transforms
14:35:27 INFO - 1500500127443 Marionette DEBUG Closed connection 370
14:35:27 INFO - 1500500127444 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49778
14:35:27 INFO - 1500500127445 Marionette DEBUG Closed connection 371
14:35:27 INFO - 1500500127445 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49779
14:35:27 INFO - 1500500127446 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:27 INFO - 1500500127447 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127450 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127453 Marionette TRACE 372 <- [1,1,null,{"sessionId":"455e51e0-c0c5-42d9-acac-25b56b386e17","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127455 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
14:35:27 INFO - 1500500127455 Marionette TRACE 372 <- [1,2,null,{}]
14:35:27 INFO - 1500500127456 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:35:27 INFO - 1500500127457 Marionette TRACE 372 <- [1,3,null,{}]
14:35:27 INFO - 1500500127458 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
14:35:27 INFO - 1500500127458 Marionette TRACE 372 <- [1,4,null,{}]
14:35:27 INFO - 1500500127459 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:35:27 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500500127462 Marionette TRACE 372 <- [1,5,null,{"value":null}]
14:35:27 INFO - 1500500127463 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
14:35:27 INFO - 1500500127464 Marionette TRACE 372 <- [1,6,null,{}]
14:35:27 INFO - 1500500127466 Marionette DEBUG Closed connection 372
14:35:27 INFO - 1500500127467 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49780
14:35:27 INFO - 1500500127468 Marionette DEBUG Closed connection 373
14:35:27 INFO - 1500500127468 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49781
14:35:27 INFO - 1500500127469 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:27 INFO - 1500500127470 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127473 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127476 Marionette TRACE 374 <- [1,1,null,{"sessionId":"6785034a-a62c-4eb1-8f0d-412c1dd6ad54","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127478 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
14:35:27 INFO - 1500500127479 Marionette TRACE 374 <- [1,2,null,{}]
14:35:27 INFO - 1500500127482 Marionette DEBUG Closed connection 374
14:35:27 INFO - 1500500127482 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49782
14:35:27 INFO - 1500500127483 Marionette DEBUG Closed connection 375
14:35:27 INFO - 1500500127484 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49783
14:35:27 INFO - 1500500127485 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:35:27 INFO - 1500500127485 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127488 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127491 Marionette TRACE 376 <- [1,1,null,{"sessionId":"bc483663-e6f4-477f-af11-07ea6dccdf3f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127493 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:35:27 INFO - 1500500127494 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:35:27 INFO - 1500500127496 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:35:27 INFO - 1500500127497 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:35:27 INFO - 1500500127516 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:35:27 INFO - 1500500127518 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:35:27 INFO - 1500500127522 Marionette TRACE 376 <- [1,2,null,{}]
14:35:27 INFO - 1500500127523 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
14:35:27 INFO - 1500500127531 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1915dd0b-6f05-429d-9730-14e449cc3364","ELEMENT":"1915dd0b-6f05-429d-9730-14e449cc3364"}}]
14:35:27 INFO - 1500500127533 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"1915dd0b-6f05-429d-9730-14e449cc3364"}]
14:35:27 INFO - 1500500127781 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:35:27 INFO - 1500500127782 Marionette TRACE 376 <- [1,4,null,{}]
14:35:27 INFO - 1500500127783 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:35:27 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500500127786 Marionette TRACE 376 <- [1,5,null,{"value":null}]
14:35:27 INFO - 1500500127787 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
14:35:27 INFO - 1500500127789 Marionette TRACE 376 <- [1,6,null,{}]
14:35:27 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 347ms
14:35:27 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
14:35:27 INFO - 1500500127791 Marionette DEBUG Closed connection 376
14:35:27 INFO - 1500500127792 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49784
14:35:27 INFO - 1500500127793 Marionette DEBUG Closed connection 377
14:35:27 INFO - 1500500127794 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49785
14:35:27 INFO - 1500500127794 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:27 INFO - 1500500127795 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127798 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127801 Marionette TRACE 378 <- [1,1,null,{"sessionId":"88e71b14-9b64-4298-9d47-2c0301743d70","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127804 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
14:35:27 INFO - 1500500127804 Marionette TRACE 378 <- [1,2,null,{}]
14:35:27 INFO - 1500500127806 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:35:27 INFO - 1500500127806 Marionette TRACE 378 <- [1,3,null,{}]
14:35:27 INFO - 1500500127807 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
14:35:27 INFO - 1500500127807 Marionette TRACE 378 <- [1,4,null,{}]
14:35:27 INFO - 1500500127809 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:35:27 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500500127811 Marionette TRACE 378 <- [1,5,null,{"value":null}]
14:35:27 INFO - 1500500127812 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
14:35:27 INFO - 1500500127813 Marionette TRACE 378 <- [1,6,null,{}]
14:35:27 INFO - 1500500127816 Marionette DEBUG Closed connection 378
14:35:27 INFO - 1500500127816 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49786
14:35:27 INFO - 1500500127817 Marionette DEBUG Closed connection 379
14:35:27 INFO - 1500500127818 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49787
14:35:27 INFO - 1500500127819 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:27 INFO - 1500500127820 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127822 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127825 Marionette TRACE 380 <- [1,1,null,{"sessionId":"050234e4-12a1-4796-b090-a53d0963614e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127827 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
14:35:27 INFO - 1500500127828 Marionette TRACE 380 <- [1,2,null,{}]
14:35:27 INFO - 1500500127830 Marionette DEBUG Closed connection 380
14:35:27 INFO - 1500500127831 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49788
14:35:27 INFO - 1500500127832 Marionette DEBUG Closed connection 381
14:35:27 INFO - 1500500127832 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49789
14:35:27 INFO - 1500500127833 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:35:27 INFO - 1500500127834 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:27 INFO - 1500500127837 Marionette DEBUG Register listener.js for window 15
14:35:27 INFO - 1500500127840 Marionette TRACE 382 <- [1,1,null,{"sessionId":"51ccff4e-2931-495a-b993-fefccc353b88","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:27 INFO - 1500500127842 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:35:27 INFO - 1500500127844 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:35:27 INFO - 1500500127845 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:35:27 INFO - 1500500127846 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:35:27 INFO - 1500500127857 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:35:27 INFO - 1500500127867 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:35:27 INFO - 1500500127871 Marionette TRACE 382 <- [1,2,null,{}]
14:35:27 INFO - 1500500127873 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
14:35:27 INFO - 1500500127881 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a249162-0394-4cb3-8a30-7b427a386cf7","ELEMENT":"5a249162-0394-4cb3-8a30-7b427a386cf7"}}]
14:35:27 INFO - 1500500127883 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"5a249162-0394-4cb3-8a30-7b427a386cf7"}]
14:35:28 INFO - 1500500128126 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:35:28 INFO - 1500500128127 Marionette TRACE 382 <- [1,4,null,{}]
14:35:28 INFO - 1500500128131 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:35:28 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500500128136 Marionette TRACE 382 <- [1,5,null,{"value":null}]
14:35:28 INFO - 1500500128138 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
14:35:28 INFO - 1500500128141 Marionette TRACE 382 <- [1,6,null,{}]
14:35:28 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 352ms
14:35:28 INFO - TEST-START | test_click.py TestClick.test_input_file
14:35:28 INFO - 1500500128144 Marionette DEBUG Closed connection 382
14:35:28 INFO - 1500500128145 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49790
14:35:28 INFO - 1500500128173 Marionette DEBUG Closed connection 383
14:35:28 INFO - 1500500128174 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49791
14:35:28 INFO - 1500500128177 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:28 INFO - 1500500128179 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128182 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128188 Marionette TRACE 384 <- [1,1,null,{"sessionId":"6136b24b-32d6-40c5-8c02-2813cf5fe7c8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128194 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
14:35:28 INFO - 1500500128195 Marionette TRACE 384 <- [1,2,null,{}]
14:35:28 INFO - 1500500128196 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:35:28 INFO - 1500500128197 Marionette TRACE 384 <- [1,3,null,{}]
14:35:28 INFO - 1500500128199 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
14:35:28 INFO - 1500500128200 Marionette TRACE 384 <- [1,4,null,{}]
14:35:28 INFO - 1500500128201 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:35:28 INFO - TEST-START: test_click.py TestClick.test_input_file1500500128205 Marionette TRACE 384 <- [1,5,null,{"value":null}]
14:35:28 INFO - 1500500128207 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
14:35:28 INFO - 1500500128208 Marionette TRACE 384 <- [1,6,null,{}]
14:35:28 INFO - 1500500128212 Marionette DEBUG Closed connection 384
14:35:28 INFO - 1500500128213 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49792
14:35:28 INFO - 1500500128214 Marionette DEBUG Closed connection 385
14:35:28 INFO - 1500500128215 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49793
14:35:28 INFO - 1500500128216 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:28 INFO - 1500500128217 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128220 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128224 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cb6a7147-109d-4cae-9f36-a5d34e8cd21a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128227 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
14:35:28 INFO - 1500500128229 Marionette TRACE 386 <- [1,2,null,{}]
14:35:28 INFO - 1500500128232 Marionette DEBUG Closed connection 386
14:35:28 INFO - 1500500128233 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49794
14:35:28 INFO - 1500500128234 Marionette DEBUG Closed connection 387
14:35:28 INFO - 1500500128235 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49795
14:35:28 INFO - 1500500128237 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:35:28 INFO - 1500500128239 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128242 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128246 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e3ef0125-b3c1-4d88-bd4a-73b192e6feba","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128249 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
14:35:28 INFO - 1500500128251 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:35:28 INFO - 1500500128254 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:35:28 INFO - 1500500128255 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:35:28 INFO - 1500500128281 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:35:28 INFO - 1500500128283 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:35:28 INFO - 1500500128288 Marionette TRACE 388 <- [1,2,null,{}]
14:35:28 INFO - 1500500128289 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
14:35:28 INFO - 1500500128298 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4538f7d5-9c9e-498c-948b-b1356df40077","ELEMENT":"4538f7d5-9c9e-498c-948b-b1356df40077"}}]
14:35:28 INFO - 1500500128376 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4538f7d5-9c9e-498c-948b-b1356df40077"}]
14:35:28 INFO - 1500500128411 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
14:35:28 INFO - 1500500128413 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:35:28 INFO - TEST-END: test_click.py TestClick.test_input_file1500500128418 Marionette TRACE 388 <- [1,5,null,{"value":null}]
14:35:28 INFO - 1500500128419 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
14:35:28 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 280ms
14:35:28 INFO - TEST-START | test_click.py TestClick.test_obscured_element
14:35:28 INFO - 1500500128422 Marionette TRACE 388 <- [1,6,null,{}]
14:35:28 INFO - 1500500128425 Marionette DEBUG Closed connection 388
14:35:28 INFO - 1500500128426 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49796
14:35:28 INFO - 1500500128428 Marionette DEBUG Closed connection 389
14:35:28 INFO - 1500500128429 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49797
14:35:28 INFO - 1500500128431 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:28 INFO - 1500500128434 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128437 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128443 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d0079abf-07b6-43a8-8778-cb43b18c0b38","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128448 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
14:35:28 INFO - 1500500128449 Marionette TRACE 390 <- [1,2,null,{}]
14:35:28 INFO - 1500500128450 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:35:28 INFO - 1500500128451 Marionette TRACE 390 <- [1,3,null,{}]
14:35:28 INFO - 1500500128452 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
14:35:28 INFO - 1500500128453 Marionette TRACE 390 <- [1,4,null,{}]
14:35:28 INFO - 1500500128454 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:35:28 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500500128458 Marionette TRACE 390 <- [1,5,null,{"value":null}]
14:35:28 INFO - 1500500128459 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
14:35:28 INFO - 1500500128461 Marionette TRACE 390 <- [1,6,null,{}]
14:35:28 INFO - 1500500128465 Marionette DEBUG Closed connection 390
14:35:28 INFO - 1500500128466 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49798
14:35:28 INFO - 1500500128466 Marionette DEBUG Closed connection 391
14:35:28 INFO - 1500500128467 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49799
14:35:28 INFO - 1500500128468 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:28 INFO - 1500500128469 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128472 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128477 Marionette TRACE 392 <- [1,1,null,{"sessionId":"4645c46d-b2ca-492e-a9f0-4a2b8168f818","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128481 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
14:35:28 INFO - 1500500128484 Marionette TRACE 392 <- [1,2,null,{}]
14:35:28 INFO - 1500500128487 Marionette DEBUG Closed connection 392
14:35:28 INFO - 1500500128487 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49800
14:35:28 INFO - 1500500128488 Marionette DEBUG Closed connection 393
14:35:28 INFO - 1500500128489 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49801
14:35:28 INFO - 1500500128490 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:35:28 INFO - 1500500128491 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128494 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128507 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f9263a26-ad93-473a-9133-a35953483c12","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128511 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:35:28 INFO - 1500500128523 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:35:28 INFO - 1500500128527 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:35:28 INFO - 1500500128527 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:35:28 INFO - 1500500128553 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:35:28 INFO - 1500500128555 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:35:28 INFO - 1500500128560 Marionette TRACE 394 <- [1,2,null,{}]
14:35:28 INFO - 1500500128568 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
14:35:28 INFO - 1500500128572 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"75b1466d-1c85-4afa-a8fb-2bd2d7206787","ELEMENT":"75b1466d-1c85-4afa-a8fb-2bd2d7206787"}}]
14:35:28 INFO - 1500500128573 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
14:35:28 INFO - 1500500128576 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4a6c0ca2-7244-40c7-a2ae-f9d1e3d0e256","ELEMENT":"4a6c0ca2-7244-40c7-a2ae-f9d1e3d0e256"}}]
14:35:28 INFO - 1500500128577 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"75b1466d-1c85-4afa-a8fb-2bd2d7206787"}]
14:35:28 INFO - 1500500128819 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:35:28 INFO - 1500500128820 Marionette TRACE 394 <- [1,5,null,{}]
14:35:28 INFO - 1500500128822 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4a6c0ca2-7244-40c7-a2ae-f9d1e3d0e256"}]
14:35:28 INFO - 1500500128840 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
14:35:28 INFO - 1500500128842 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
14:35:28 INFO - 1500500128845 Marionette TRACE 394 <- [1,7,null,{"value":false}]
14:35:28 INFO - 1500500128847 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:35:28 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500500128850 Marionette TRACE 394 <- [1,8,null,{"value":null}]
14:35:28 INFO - 1500500128851 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
14:35:28 INFO - 1500500128853 Marionette TRACE 394 <- [1,9,null,{}]
14:35:28 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 431ms
14:35:28 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
14:35:28 INFO - 1500500128855 Marionette DEBUG Closed connection 394
14:35:28 INFO - 1500500128856 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49802
14:35:28 INFO - 1500500128857 Marionette DEBUG Closed connection 395
14:35:28 INFO - 1500500128858 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49803
14:35:28 INFO - 1500500128863 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:28 INFO - 1500500128865 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128869 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128873 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a0c234e4-8f8c-4833-b64f-3445f43dd1b1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128876 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
14:35:28 INFO - 1500500128876 Marionette TRACE 396 <- [1,2,null,{}]
14:35:28 INFO - 1500500128877 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:35:28 INFO - 1500500128878 Marionette TRACE 396 <- [1,3,null,{}]
14:35:28 INFO - 1500500128879 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
14:35:28 INFO - 1500500128879 Marionette TRACE 396 <- [1,4,null,{}]
14:35:28 INFO - 1500500128881 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:35:28 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500500128886 Marionette TRACE 396 <- [1,5,null,{"value":null}]
14:35:28 INFO - 1500500128887 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
14:35:28 INFO - 1500500128888 Marionette TRACE 396 <- [1,6,null,{}]
14:35:28 INFO - 1500500128891 Marionette DEBUG Closed connection 396
14:35:28 INFO - 1500500128891 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49804
14:35:28 INFO - 1500500128897 Marionette DEBUG Closed connection 397
14:35:28 INFO - 1500500128897 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49805
14:35:28 INFO - 1500500128904 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:35:28 INFO - 1500500128906 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128909 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128914 Marionette TRACE 398 <- [1,1,null,{"sessionId":"635be8bd-026b-4907-a7f1-1bc19332fb9b","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128917 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
14:35:28 INFO - 1500500128919 Marionette TRACE 398 <- [1,2,null,{}]
14:35:28 INFO - 1500500128922 Marionette DEBUG Closed connection 398
14:35:28 INFO - 1500500128924 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49806
14:35:28 INFO - 1500500128925 Marionette DEBUG Closed connection 399
14:35:28 INFO - 1500500128926 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49807
14:35:28 INFO - 1500500128927 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:35:28 INFO - 1500500128928 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}
14:35:28 INFO - 1500500128931 Marionette DEBUG Register listener.js for window 15
14:35:28 INFO - 1500500128936 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b8d35fd1-b5d5-4974-92c3-a92466d61b8f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1368,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-308.001\\AppData\\Local\\Temp\\tmppagooc.mozrunner","moz:accessibilityChecks":false}}]
14:35:28 INFO - 1500500128938 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:35:28 INFO - 1500500128940 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:35:28 INFO - 1500500128943 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:35:28 INFO - 1500500128943 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:35:28 INFO - 1500500128969 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:35:28 INFO - 1500500128971 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:35:28 INFO - 1500500128975 Marionette TRACE 400 <- [1,2,null,{}]
14:35:28 INFO - 1500500128983 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
14:35:28 INFO - 1500500128987 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d716ed4-0f0b-4b73-a717-e0b875bb1cb3","ELEMENT":"8d716ed4-0f0b-4b73-a717-e0b875bb1cb3"}}]
14:35:28 INFO - 1500500128988 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8d716ed4-0f0b-4b73-a717-e0b875bb1cb3"}]
14:35:29 INFO - 1500500129015 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
14:35:29 INFO - 1500500129016 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"8d716ed4-0f0b-4b73-a717-e0b875bb1cb3"}]
14:35:29 INFO - 1500500129037 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element