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]
03:46:19 INFO - 1499165179591 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}]
03:46:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499165179593 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:46:19 INFO - 1499165179594 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:46:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 114ms
03:46:19 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:46:19 INFO - 1499165179595 Marionette TRACE 352 <- [1,6,null,{}]
03:46:19 INFO - 1499165179596 Marionette DEBUG Closed connection 352
03:46:19 INFO - 1499165179597 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49727
03:46:19 INFO - 1499165179598 Marionette DEBUG Closed connection 353
03:46:19 INFO - 1499165179598 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49728
03:46:19 INFO - 1499165179599 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:19 INFO - 1499165179600 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:19 INFO - 1499165179602 Marionette DEBUG Register listener.js for window 4294967297
03:46:19 INFO - 1499165179604 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b7158b01-57e5-4b13-9404-3e33ae094201","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:19 INFO - 1499165179605 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:46:19 INFO - 1499165179606 Marionette TRACE 354 <- [1,2,null,{}]
03:46:19 INFO - 1499165179606 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:46:19 INFO - 1499165179607 Marionette TRACE 354 <- [1,3,null,{}]
03:46:19 INFO - 1499165179608 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:46:19 INFO - 1499165179608 Marionette TRACE 354 <- [1,4,null,{}]
03:46:19 INFO - 1499165179609 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}]
03:46:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499165179612 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:46:19 INFO - 1499165179612 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:46:19 INFO - 1499165179613 Marionette TRACE 354 <- [1,6,null,{}]
03:46:19 INFO - 1499165179615 Marionette DEBUG Closed connection 354
03:46:19 INFO - 1499165179615 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49729
03:46:19 INFO - 1499165179616 Marionette DEBUG Closed connection 355
03:46:19 INFO - 1499165179617 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49730
03:46:19 INFO - 1499165179618 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:19 INFO - 1499165179618 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:19 INFO - 1499165179620 Marionette DEBUG Register listener.js for window 4294967297
03:46:19 INFO - 1499165179623 Marionette TRACE 356 <- [1,1,null,{"sessionId":"704a92d5-8382-4871-8f10-f21f0be3ada4","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:19 INFO - 1499165179624 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:46:19 INFO - 1499165179625 Marionette TRACE 356 <- [1,2,null,{}]
03:46:19 INFO - 1499165179627 Marionette DEBUG Closed connection 356
03:46:19 INFO - 1499165179627 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49731
03:46:19 INFO - 1499165179628 Marionette DEBUG Closed connection 357
03:46:19 INFO - 1499165179629 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49732
03:46:19 INFO - 1499165179630 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:46:19 INFO - 1499165179631 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:19 INFO - 1499165179633 Marionette DEBUG Register listener.js for window 4294967297
03:46:19 INFO - 1499165179636 Marionette TRACE 358 <- [1,1,null,{"sessionId":"cd15e014-5577-4ece-97b6-11ca9fb6f1ad","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:19 INFO - 1499165179637 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49291/clicks.html"}]
03:46:19 INFO - 1499165179639 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49291/hidden.html"
03:46:19 INFO - 1499165179645 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49291/hidden.html"
03:46:19 INFO - 1499165179645 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49291/hidden.html"
03:46:19 INFO - 1499165179650 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49291/clicks.html"
03:46:19 INFO - 1499165179656 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49291/clicks.html"
03:46:19 INFO - 1499165179659 Marionette TRACE 358 <- [1,2,null,{}]
03:46:19 INFO - 1499165179663 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:46:19 INFO - 1499165179666 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4ce2b00-a0ad-4bed-af38-97ea3d73af65","ELEMENT":"a4ce2b00-a0ad-4bed-af38-97ea3d73af65"}}]
03:46:19 INFO - 1499165179667 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a4ce2b00-a0ad-4bed-af38-97ea3d73af65"}]
03:46:19 INFO - 1499165179713 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49291/clicks.html"
03:46:19 INFO - 1499165179718 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49291/clicks.html"
03:46:19 INFO - 1499165179719 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49291/clicks.html"
03:46:19 INFO - 1499165179726 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49291/test.html"
03:46:19 INFO - 1499165179730 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49291/test.html"
03:46:19 INFO - 1499165179733 Marionette TRACE 358 <- [1,4,null,{}]
03:46:19 INFO - 1499165179736 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:46:19 INFO - 1499165179741 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0a96795c-11f9-4b95-b72c-ec9d44bb94e1","ELEMENT":"0a96795c-11f9-4b95-b72c-ec9d44bb94e1"}}]
03:46:19 INFO - 1499165179743 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:46:19 INFO - 1499165179743 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:46:19 INFO - 1499165179745 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}]
03:46:19 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499165179755 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:46:19 INFO - 1499165179756 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:46:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 162ms
03:46:19 INFO - TEST-START | test_click.py TestClick.test_container_element
03:46:19 INFO - 1499165179757 Marionette TRACE 358 <- [1,8,null,{}]
03:46:19 INFO - 1499165179759 Marionette DEBUG Closed connection 358
03:46:19 INFO - 1499165179759 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49735
03:46:19 INFO - 1499165179760 Marionette DEBUG Closed connection 359
03:46:19 INFO - 1499165179761 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49736
03:46:19 INFO - 1499165179762 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:19 INFO - 1499165179762 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:19 INFO - 1499165179770 Marionette DEBUG Register listener.js for window 4294967297
03:46:19 INFO - 1499165179774 Marionette TRACE 360 <- [1,1,null,{"sessionId":"a2f4229f-c1f7-4aa6-8f67-ab2e57ab1c7a","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:19 INFO - 1499165179775 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:46:19 INFO - 1499165179775 Marionette TRACE 360 <- [1,2,null,{}]
03:46:19 INFO - 1499165179776 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:46:19 INFO - 1499165179776 Marionette TRACE 360 <- [1,3,null,{}]
03:46:19 INFO - 1499165179777 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:46:19 INFO - 1499165179777 Marionette TRACE 360 <- [1,4,null,{}]
03:46:19 INFO - 1499165179779 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}]
03:46:19 INFO - TEST-START: test_click.py TestClick.test_container_element1499165179781 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:46:19 INFO - 1499165179782 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:46:19 INFO - 1499165179783 Marionette TRACE 360 <- [1,6,null,{}]
03:46:19 INFO - 1499165179785 Marionette DEBUG Closed connection 360
03:46:19 INFO - 1499165179785 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49737
03:46:19 INFO - 1499165179786 Marionette DEBUG Closed connection 361
03:46:19 INFO - 1499165179786 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49738
03:46:19 INFO - 1499165179788 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:19 INFO - 1499165179788 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:19 INFO - 1499165179791 Marionette DEBUG Register listener.js for window 4294967297
03:46:19 INFO - 1499165179794 Marionette TRACE 362 <- [1,1,null,{"sessionId":"77e7795a-ff28-4d90-af81-5c5a7a1c1091","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:19 INFO - 1499165179803 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:46:19 INFO - 1499165179805 Marionette TRACE 362 <- [1,2,null,{}]
03:46:19 INFO - 1499165179806 Marionette DEBUG Closed connection 362
03:46:19 INFO - 1499165179807 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49739
03:46:19 INFO - 1499165179807 Marionette DEBUG Closed connection 363
03:46:19 INFO - 1499165179808 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49740
03:46:19 INFO - 1499165179809 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:46:19 INFO - 1499165179809 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:19 INFO - 1499165179834 Marionette DEBUG Register listener.js for window 4294967297
03:46:19 INFO - 1499165179837 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a44e15f9-d9cb-466f-90d7-b3a1dd921a17","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:19 INFO - 1499165179838 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"}]
03:46:19 INFO - 1499165179844 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49291/test.html"
03:46:19 INFO - 1499165179846 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49291/test.html"
03:46:19 INFO - 1499165179847 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49291/test.html"
03:46:19 INFO - 1499165179854 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"
03:46:19 INFO - 1499165179859 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"
03:46:19 INFO - 1499165179865 Marionette TRACE 364 <- [1,2,null,{}]
03:46:19 INFO - 1499165179868 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:46:19 INFO - 1499165179870 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b9d64d51-269a-4c1c-9ceb-a0ac1fd0564c","ELEMENT":"b9d64d51-269a-4c1c-9ceb-a0ac1fd0564c"}}]
03:46:19 INFO - 1499165179871 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"b9d64d51-269a-4c1c-9ceb-a0ac1fd0564c"}]
03:46:20 INFO - 1499165180110 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:46:20 INFO - 1499165180111 Marionette TRACE 364 <- [1,4,null,{}]
03:46:20 INFO - 1499165180112 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"b9d64d51-269a-4c1c-9ceb-a0ac1fd0564c","name":"selected"}]
03:46:20 INFO - 1499165180116 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:46:20 INFO - 1499165180117 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}]
03:46:20 INFO - TEST-END: test_click.py TestClick.test_container_element1499165180119 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:46:20 INFO - 1499165180120 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:46:20 INFO - 1499165180121 Marionette TRACE 364 <- [1,7,null,{}]
03:46:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 363ms
03:46:20 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:46:20 INFO - 1499165180123 Marionette DEBUG Closed connection 364
03:46:20 INFO - 1499165180124 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49741
03:46:20 INFO - 1499165180124 Marionette DEBUG Closed connection 365
03:46:20 INFO - 1499165180125 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49742
03:46:20 INFO - 1499165180126 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:20 INFO - 1499165180126 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180128 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180131 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ac2d58f8-d2c1-4e8b-808a-f63d0e4b2c40","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180132 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:46:20 INFO - 1499165180133 Marionette TRACE 366 <- [1,2,null,{}]
03:46:20 INFO - 1499165180134 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:46:20 INFO - 1499165180134 Marionette TRACE 366 <- [1,3,null,{}]
03:46:20 INFO - 1499165180135 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:46:20 INFO - 1499165180135 Marionette TRACE 366 <- [1,4,null,{}]
03:46:20 INFO - 1499165180138 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}]
03:46:20 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499165180140 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:46:20 INFO - 1499165180141 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:46:20 INFO - 1499165180142 Marionette TRACE 366 <- [1,6,null,{}]
03:46:20 INFO - 1499165180144 Marionette DEBUG Closed connection 366
03:46:20 INFO - 1499165180145 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49743
03:46:20 INFO - 1499165180145 Marionette DEBUG Closed connection 367
03:46:20 INFO - 1499165180146 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49744
03:46:20 INFO - 1499165180147 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:20 INFO - 1499165180148 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180150 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180152 Marionette TRACE 368 <- [1,1,null,{"sessionId":"4645fb2a-5578-4af6-a005-a8faee425a2e","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180153 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:46:20 INFO - 1499165180154 Marionette TRACE 368 <- [1,2,null,{}]
03:46:20 INFO - 1499165180156 Marionette DEBUG Closed connection 368
03:46:20 INFO - 1499165180156 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49745
03:46:20 INFO - 1499165180157 Marionette DEBUG Closed connection 369
03:46:20 INFO - 1499165180157 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49746
03:46:20 INFO - 1499165180158 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:46:20 INFO - 1499165180159 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180161 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180163 Marionette TRACE 370 <- [1,1,null,{"sessionId":"fe52a744-2c73-4593-bc1d-4edc2cae0eb5","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180164 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"}]
03:46:20 INFO - 1499165180166 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"
03:46:20 INFO - 1499165180167 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"
03:46:20 INFO - 1499165180168 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"
03:46:20 INFO - 1499165180175 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"
03:46:20 INFO - 1499165180176 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"
03:46:20 INFO - 1499165180181 Marionette TRACE 370 <- [1,2,null,{}]
03:46:20 INFO - 1499165180183 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:46:20 INFO - 1499165180186 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d92b9044-5fa4-4c50-967b-ff6506d12f92","ELEMENT":"d92b9044-5fa4-4c50-967b-ff6506d12f92"}}]
03:46:20 INFO - 1499165180186 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d92b9044-5fa4-4c50-967b-ff6506d12f92"}]
03:46:20 INFO - 1499165180427 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:46:20 INFO - 1499165180428 Marionette TRACE 370 <- [1,4,null,{}]
03:46:20 INFO - 1499165180429 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d92b9044-5fa4-4c50-967b-ff6506d12f92","name":"selected"}]
03:46:20 INFO - 1499165180433 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:46:20 INFO - 1499165180434 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}]
03:46:20 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499165180436 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:46:20 INFO - 1499165180437 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:46:20 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 316ms
03:46:20 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:46:20 INFO - 1499165180438 Marionette TRACE 370 <- [1,7,null,{}]
03:46:20 INFO - 1499165180439 Marionette DEBUG Closed connection 370
03:46:20 INFO - 1499165180440 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49747
03:46:20 INFO - 1499165180441 Marionette DEBUG Closed connection 371
03:46:20 INFO - 1499165180442 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49748
03:46:20 INFO - 1499165180443 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:20 INFO - 1499165180443 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180445 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180448 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8faf043a-45ec-49e0-a20d-31c435b399c1","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180449 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:46:20 INFO - 1499165180450 Marionette TRACE 372 <- [1,2,null,{}]
03:46:20 INFO - 1499165180451 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:46:20 INFO - 1499165180451 Marionette TRACE 372 <- [1,3,null,{}]
03:46:20 INFO - 1499165180452 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:46:20 INFO - 1499165180452 Marionette TRACE 372 <- [1,4,null,{}]
03:46:20 INFO - 1499165180454 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}]
03:46:20 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499165180456 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:46:20 INFO - 1499165180457 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:46:20 INFO - 1499165180459 Marionette TRACE 372 <- [1,6,null,{}]
03:46:20 INFO - 1499165180460 Marionette DEBUG Closed connection 372
03:46:20 INFO - 1499165180460 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49749
03:46:20 INFO - 1499165180461 Marionette DEBUG Closed connection 373
03:46:20 INFO - 1499165180462 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49750
03:46:20 INFO - 1499165180463 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:20 INFO - 1499165180463 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180465 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180468 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ffc13825-d1b5-489a-91e5-43f428385009","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180469 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:46:20 INFO - 1499165180470 Marionette TRACE 374 <- [1,2,null,{}]
03:46:20 INFO - 1499165180471 Marionette DEBUG Closed connection 374
03:46:20 INFO - 1499165180471 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49751
03:46:20 INFO - 1499165180472 Marionette DEBUG Closed connection 375
03:46:20 INFO - 1499165180473 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49752
03:46:20 INFO - 1499165180473 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:46:20 INFO - 1499165180474 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180476 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180478 Marionette TRACE 376 <- [1,1,null,{"sessionId":"a46346ed-9214-402c-ac74-f670945b44d9","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180479 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"}]
03:46:20 INFO - 1499165180481 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"
03:46:20 INFO - 1499165180483 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"
03:46:20 INFO - 1499165180483 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"
03:46:20 INFO - 1499165180491 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"
03:46:20 INFO - 1499165180492 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"
03:46:20 INFO - 1499165180520 Marionette TRACE 376 <- [1,2,null,{}]
03:46:20 INFO - 1499165180523 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:46:20 INFO - 1499165180525 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7771db80-60b8-4bb3-80ab-52a139fb537a","ELEMENT":"7771db80-60b8-4bb3-80ab-52a139fb537a"}}]
03:46:20 INFO - 1499165180526 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"7771db80-60b8-4bb3-80ab-52a139fb537a"}]
03:46:20 INFO - 1499165180774 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:46:20 INFO - 1499165180775 Marionette TRACE 376 <- [1,4,null,{}]
03:46:20 INFO - 1499165180776 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}]
03:46:20 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499165180779 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:46:20 INFO - 1499165180780 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:46:20 INFO - 1499165180781 Marionette TRACE 376 <- [1,6,null,{}]
03:46:20 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 343ms
03:46:20 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:46:20 INFO - 1499165180782 Marionette DEBUG Closed connection 376
03:46:20 INFO - 1499165180784 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49753
03:46:20 INFO - 1499165180785 Marionette DEBUG Closed connection 377
03:46:20 INFO - 1499165180786 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49754
03:46:20 INFO - 1499165180786 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:20 INFO - 1499165180787 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180789 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180792 Marionette TRACE 378 <- [1,1,null,{"sessionId":"9b1b1f50-7fcc-4ff1-9535-fc72b469e5df","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180793 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:46:20 INFO - 1499165180793 Marionette TRACE 378 <- [1,2,null,{}]
03:46:20 INFO - 1499165180794 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:46:20 INFO - 1499165180794 Marionette TRACE 378 <- [1,3,null,{}]
03:46:20 INFO - 1499165180795 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:46:20 INFO - 1499165180796 Marionette TRACE 378 <- [1,4,null,{}]
03:46:20 INFO - 1499165180797 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}]
03:46:20 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499165180799 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:46:20 INFO - 1499165180800 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:46:20 INFO - 1499165180801 Marionette TRACE 378 <- [1,6,null,{}]
03:46:20 INFO - 1499165180802 Marionette DEBUG Closed connection 378
03:46:20 INFO - 1499165180803 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49755
03:46:20 INFO - 1499165180803 Marionette DEBUG Closed connection 379
03:46:20 INFO - 1499165180804 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49756
03:46:20 INFO - 1499165180805 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:20 INFO - 1499165180805 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180807 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180810 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ad518629-d5d7-4486-8638-17981730a980","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180811 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:46:20 INFO - 1499165180812 Marionette TRACE 380 <- [1,2,null,{}]
03:46:20 INFO - 1499165180813 Marionette DEBUG Closed connection 380
03:46:20 INFO - 1499165180814 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49757
03:46:20 INFO - 1499165180814 Marionette DEBUG Closed connection 381
03:46:20 INFO - 1499165180815 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49758
03:46:20 INFO - 1499165180816 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:46:20 INFO - 1499165180816 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:20 INFO - 1499165180818 Marionette DEBUG Register listener.js for window 4294967297
03:46:20 INFO - 1499165180821 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ff4ef32c-af43-47e7-acd9-3c4e09b5a318","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:20 INFO - 1499165180822 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"}]
03:46:20 INFO - 1499165180824 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"
03:46:20 INFO - 1499165180825 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"
03:46:20 INFO - 1499165180825 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"
03:46:20 INFO - 1499165180830 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"
03:46:20 INFO - 1499165180833 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"
03:46:20 INFO - 1499165180835 Marionette TRACE 382 <- [1,2,null,{}]
03:46:20 INFO - 1499165180837 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:46:20 INFO - 1499165180842 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f246e36f-1b6c-46aa-a533-efb47149d9ed","ELEMENT":"f246e36f-1b6c-46aa-a533-efb47149d9ed"}}]
03:46:20 INFO - 1499165180843 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f246e36f-1b6c-46aa-a533-efb47149d9ed"}]
03:46:21 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 305ms
03:46:21 INFO - 1499165181080 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:46:21 INFO - TEST-START | test_click.py TestClick.test_input_file
03:46:21 INFO - 1499165181081 Marionette TRACE 382 <- [1,4,null,{}]
03:46:21 INFO - 1499165181083 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}]
03:46:21 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499165181085 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:46:21 INFO - 1499165181085 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:46:21 INFO - 1499165181087 Marionette TRACE 382 <- [1,6,null,{}]
03:46:21 INFO - 1499165181088 Marionette DEBUG Closed connection 382
03:46:21 INFO - 1499165181089 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49759
03:46:21 INFO - 1499165181090 Marionette DEBUG Closed connection 383
03:46:21 INFO - 1499165181091 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49760
03:46:21 INFO - 1499165181091 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:21 INFO - 1499165181092 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181094 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181096 Marionette TRACE 384 <- [1,1,null,{"sessionId":"9259f00c-d64d-407a-9757-ce9e16940a8f","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181098 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:46:21 INFO - 1499165181098 Marionette TRACE 384 <- [1,2,null,{}]
03:46:21 INFO - 1499165181099 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:46:21 INFO - 1499165181099 Marionette TRACE 384 <- [1,3,null,{}]
03:46:21 INFO - 1499165181100 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:46:21 INFO - 1499165181101 Marionette TRACE 384 <- [1,4,null,{}]
03:46:21 INFO - 1499165181102 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}]
03:46:21 INFO - TEST-START: test_click.py TestClick.test_input_file1499165181104 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:46:21 INFO - 1499165181105 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:46:21 INFO - 1499165181106 Marionette TRACE 384 <- [1,6,null,{}]
03:46:21 INFO - 1499165181107 Marionette DEBUG Closed connection 384
03:46:21 INFO - 1499165181108 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49761
03:46:21 INFO - 1499165181109 Marionette DEBUG Closed connection 385
03:46:21 INFO - 1499165181109 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49762
03:46:21 INFO - 1499165181110 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:21 INFO - 1499165181111 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181113 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181115 Marionette TRACE 386 <- [1,1,null,{"sessionId":"46eb7ecd-0796-4393-a620-c3e03225a9ea","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181117 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:46:21 INFO - 1499165181118 Marionette TRACE 386 <- [1,2,null,{}]
03:46:21 INFO - 1499165181119 Marionette DEBUG Closed connection 386
03:46:21 INFO - 1499165181119 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49763
03:46:21 INFO - 1499165181120 Marionette DEBUG Closed connection 387
03:46:21 INFO - 1499165181121 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49764
03:46:21 INFO - 1499165181122 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:46:21 INFO - 1499165181122 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181124 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181127 Marionette TRACE 388 <- [1,1,null,{"sessionId":"37a88f53-bc79-4b7a-9cb0-876bfe660790","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181128 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:46:21 INFO - 1499165181130 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"
03:46:21 INFO - 1499165181131 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"
03:46:21 INFO - 1499165181131 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"
03:46:21 INFO - 1499165181137 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:46:21 INFO - 1499165181141 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:46:21 INFO - 1499165181145 Marionette TRACE 388 <- [1,2,null,{}]
03:46:21 INFO - 1499165181148 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:46:21 INFO - 1499165181150 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1a2286a5-831d-4570-bf53-ae1804109aa2","ELEMENT":"1a2286a5-831d-4570-bf53-ae1804109aa2"}}]
03:46:21 INFO - 1499165181151 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"1a2286a5-831d-4570-bf53-ae1804109aa2"}]
03:46:21 INFO - 1499165181182 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]
03:46:21 INFO - 1499165181184 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}]
03:46:21 INFO - TEST-END: test_click.py TestClick.test_input_file1499165181187 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:46:21 INFO - 1499165181188 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:46:21 INFO - 1499165181190 Marionette TRACE 388 <- [1,6,null,{}]
03:46:21 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 102ms
03:46:21 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:46:21 INFO - 1499165181191 Marionette DEBUG Closed connection 388
03:46:21 INFO - 1499165181192 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49765
03:46:21 INFO - 1499165181192 Marionette DEBUG Closed connection 389
03:46:21 INFO - 1499165181193 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49766
03:46:21 INFO - 1499165181194 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:21 INFO - 1499165181194 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181197 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181199 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7a1e06ac-7012-43b1-bc67-3b5a41e25529","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181200 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:46:21 INFO - 1499165181200 Marionette TRACE 390 <- [1,2,null,{}]
03:46:21 INFO - 1499165181201 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:46:21 INFO - 1499165181201 Marionette TRACE 390 <- [1,3,null,{}]
03:46:21 INFO - 1499165181202 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:46:21 INFO - 1499165181203 Marionette TRACE 390 <- [1,4,null,{}]
03:46:21 INFO - 1499165181204 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}]
03:46:21 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499165181206 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:46:21 INFO - 1499165181207 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:46:21 INFO - 1499165181208 Marionette TRACE 390 <- [1,6,null,{}]
03:46:21 INFO - 1499165181210 Marionette DEBUG Closed connection 390
03:46:21 INFO - 1499165181210 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49767
03:46:21 INFO - 1499165181211 Marionette DEBUG Closed connection 391
03:46:21 INFO - 1499165181211 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49768
03:46:21 INFO - 1499165181212 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:21 INFO - 1499165181212 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181215 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181217 Marionette TRACE 392 <- [1,1,null,{"sessionId":"2b5d54c0-9b3e-4cc6-ba53-bb3a35fa2927","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181218 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:46:21 INFO - 1499165181219 Marionette TRACE 392 <- [1,2,null,{}]
03:46:21 INFO - 1499165181220 Marionette DEBUG Closed connection 392
03:46:21 INFO - 1499165181221 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49769
03:46:21 INFO - 1499165181222 Marionette DEBUG Closed connection 393
03:46:21 INFO - 1499165181222 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49770
03:46:21 INFO - 1499165181223 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:46:21 INFO - 1499165181224 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181226 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181228 Marionette TRACE 394 <- [1,1,null,{"sessionId":"5163819a-1426-4b80-8158-2f0e79f2829e","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181229 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"}]
03:46:21 INFO - 1499165181231 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:46:21 INFO - 1499165181233 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:46:21 INFO - 1499165181233 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:46:21 INFO - 1499165181240 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"
03:46:21 INFO - 1499165181241 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"
03:46:21 INFO - 1499165181245 Marionette TRACE 394 <- [1,2,null,{}]
03:46:21 INFO - 1499165181248 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:46:21 INFO - 1499165181250 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"14a6f4c6-a968-44c1-8a93-57651b286ac6","ELEMENT":"14a6f4c6-a968-44c1-8a93-57651b286ac6"}}]
03:46:21 INFO - 1499165181251 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:46:21 INFO - 1499165181253 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"46e137eb-7e08-49f4-b57f-2da3e8a9b8f5","ELEMENT":"46e137eb-7e08-49f4-b57f-2da3e8a9b8f5"}}]
03:46:21 INFO - 1499165181254 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"14a6f4c6-a968-44c1-8a93-57651b286ac6"}]
03:46:21 INFO - 1499165181490 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:46:21 INFO - 1499165181491 Marionette TRACE 394 <- [1,5,null,{}]
03:46:21 INFO - 1499165181492 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"46e137eb-7e08-49f4-b57f-2da3e8a9b8f5"}]
03:46:21 INFO - 1499165181506 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
03:46:21 INFO - 1499165181507 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:46:21 INFO - 1499165181510 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:46:21 INFO - 1499165181511 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}]
03:46:21 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499165181513 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:46:21 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 325ms
03:46:21 INFO - 1499165181514 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:46:21 INFO - 1499165181515 Marionette TRACE 394 <- [1,9,null,{}]
03:46:21 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:46:21 INFO - 1499165181516 Marionette DEBUG Closed connection 394
03:46:21 INFO - 1499165181518 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49771
03:46:21 INFO - 1499165181519 Marionette DEBUG Closed connection 395
03:46:21 INFO - 1499165181519 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49772
03:46:21 INFO - 1499165181520 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:21 INFO - 1499165181528 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181531 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181534 Marionette TRACE 396 <- [1,1,null,{"sessionId":"70c4b890-2280-431e-aaaa-ac528c40f925","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181535 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:46:21 INFO - 1499165181536 Marionette TRACE 396 <- [1,2,null,{}]
03:46:21 INFO - 1499165181537 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:46:21 INFO - 1499165181537 Marionette TRACE 396 <- [1,3,null,{}]
03:46:21 INFO - 1499165181538 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:46:21 INFO - 1499165181538 Marionette TRACE 396 <- [1,4,null,{}]
03:46:21 INFO - 1499165181540 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}]
03:46:21 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499165181542 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:46:21 INFO - 1499165181543 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:46:21 INFO - 1499165181544 Marionette TRACE 396 <- [1,6,null,{}]
03:46:21 INFO - 1499165181546 Marionette DEBUG Closed connection 396
03:46:21 INFO - 1499165181546 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49773
03:46:21 INFO - 1499165181547 Marionette DEBUG Closed connection 397
03:46:21 INFO - 1499165181547 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49774
03:46:21 INFO - 1499165181548 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:46:21 INFO - 1499165181549 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181551 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181553 Marionette TRACE 398 <- [1,1,null,{"sessionId":"74b9471e-87a6-429d-87fc-babe9047793e","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181554 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:46:21 INFO - 1499165181555 Marionette TRACE 398 <- [1,2,null,{}]
03:46:21 INFO - 1499165181557 Marionette DEBUG Closed connection 398
03:46:21 INFO - 1499165181557 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49775
03:46:21 INFO - 1499165181558 Marionette DEBUG Closed connection 399
03:46:21 INFO - 1499165181558 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49776
03:46:21 INFO - 1499165181559 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:46:21 INFO - 1499165181560 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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}
03:46:21 INFO - 1499165181562 Marionette DEBUG Register listener.js for window 4294967297
03:46:21 INFO - 1499165181565 Marionette TRACE 400 <- [1,1,null,{"sessionId":"b6c9263f-3be1-4e12-8196-c95512a08e45","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":3984,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-140.000\\AppData\\Local\\Temp\\tmpju8nxb.mozrunner","moz:accessibilityChecks":false}}]
03:46:21 INFO - 1499165181565 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"}]
03:46:21 INFO - 1499165181567 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"
03:46:21 INFO - 1499165181568 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"
03:46:21 INFO - 1499165181569 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"
03:46:21 INFO - 1499165181576 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"
03:46:21 INFO - 1499165181578 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"
03:46:21 INFO - 1499165181581 Marionette TRACE 400 <- [1,2,null,{}]
03:46:21 INFO - 1499165181584 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:46:21 INFO - 1499165181586 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"45268364-2509-474c-8622-45bd1b7415f2","ELEMENT":"45268364-2509-474c-8622-45bd1b7415f2"}}]
03:46:21 INFO - 1499165181587 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"45268364-2509-474c-8622-45bd1b7415f2"}]
03:46:21 INFO - 1499165181614 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:46:21 INFO - 1499165181615 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"45268364-2509-474c-8622-45bd1b7415f2"}]
03:46:21 INFO - 1499165181631 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element