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]
02:50:20 INFO - 1499161820514 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}]
02:50:20 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499161820517 Marionette TRACE 352 <- [1,5,null,{"value":null}]
02:50:20 INFO - 1499161820518 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
02:50:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 148ms
02:50:20 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
02:50:20 INFO - 1499161820520 Marionette TRACE 352 <- [1,6,null,{}]
02:50:20 INFO - 1499161820522 Marionette DEBUG Closed connection 352
02:50:20 INFO - 1499161820522 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49819
02:50:20 INFO - 1499161820523 Marionette DEBUG Closed connection 353
02:50:20 INFO - 1499161820524 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49820
02:50:20 INFO - 1499161820525 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:20 INFO - 1499161820526 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:20 INFO - 1499161820529 Marionette DEBUG Register listener.js for window 4294967297
02:50:20 INFO - 1499161820533 Marionette TRACE 354 <- [1,1,null,{"sessionId":"02f12582-140f-4944-9b51-32359788150f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:20 INFO - 1499161820534 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
02:50:20 INFO - 1499161820535 Marionette TRACE 354 <- [1,2,null,{}]
02:50:20 INFO - 1499161820536 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:20 INFO - 1499161820536 Marionette TRACE 354 <- [1,3,null,{}]
02:50:20 INFO - 1499161820537 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:20 INFO - 1499161820538 Marionette TRACE 354 <- [1,4,null,{}]
02:50:20 INFO - 1499161820540 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}]
02:50:20 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499161820543 Marionette TRACE 354 <- [1,5,null,{"value":null}]
02:50:20 INFO - 1499161820544 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
02:50:20 INFO - 1499161820546 Marionette TRACE 354 <- [1,6,null,{}]
02:50:20 INFO - 1499161820547 Marionette DEBUG Closed connection 354
02:50:20 INFO - 1499161820548 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49821
02:50:20 INFO - 1499161820549 Marionette DEBUG Closed connection 355
02:50:20 INFO - 1499161820550 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49822
02:50:20 INFO - 1499161820551 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:20 INFO - 1499161820552 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:20 INFO - 1499161820555 Marionette DEBUG Register listener.js for window 4294967297
02:50:20 INFO - 1499161820558 Marionette TRACE 356 <- [1,1,null,{"sessionId":"15259ef3-6106-46ca-99df-718683240af5","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:20 INFO - 1499161820560 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
02:50:20 INFO - 1499161820561 Marionette TRACE 356 <- [1,2,null,{}]
02:50:20 INFO - 1499161820563 Marionette DEBUG Closed connection 356
02:50:20 INFO - 1499161820564 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49823
02:50:20 INFO - 1499161820565 Marionette DEBUG Closed connection 357
02:50:20 INFO - 1499161820565 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49824
02:50:20 INFO - 1499161820567 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:20 INFO - 1499161820567 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:20 INFO - 1499161820570 Marionette DEBUG Register listener.js for window 4294967297
02:50:20 INFO - 1499161820574 Marionette TRACE 358 <- [1,1,null,{"sessionId":"f9ac89b4-620c-48b6-9d50-598653c307b2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:20 INFO - 1499161820576 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49373/clicks.html"}]
02:50:20 INFO - 1499161820578 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49373/hidden.html"
02:50:20 INFO - 1499161820587 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49373/hidden.html"
02:50:20 INFO - 1499161820588 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49373/hidden.html"
02:50:20 INFO - 1499161820595 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49373/clicks.html"
02:50:20 INFO - 1499161820601 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49373/clicks.html"
02:50:20 INFO - 1499161820616 Marionette TRACE 358 <- [1,2,null,{}]
02:50:20 INFO - 1499161820633 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
02:50:20 INFO - 1499161820636 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4e5e6f70-eb87-4f9d-b542-58f2088cf57a","ELEMENT":"4e5e6f70-eb87-4f9d-b542-58f2088cf57a"}}]
02:50:20 INFO - 1499161820637 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"4e5e6f70-eb87-4f9d-b542-58f2088cf57a"}]
02:50:20 INFO - 1499161820682 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49373/clicks.html"
02:50:20 INFO - 1499161820691 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49373/clicks.html"
02:50:20 INFO - 1499161820692 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49373/clicks.html"
02:50:20 INFO - 1499161820702 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49373/test.html"
02:50:20 INFO - 1499161820709 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49373/test.html"
02:50:20 INFO - 1499161820713 Marionette TRACE 358 <- [1,4,null,{}]
02:50:20 INFO - 1499161820718 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
02:50:20 INFO - 1499161820722 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3bc4b9d0-8c67-4529-99ca-152fbc88d162","ELEMENT":"3bc4b9d0-8c67-4529-99ca-152fbc88d162"}}]
02:50:20 INFO - 1499161820729 Marionette TRACE 358 -> [0,6,"getTitle",{}]
02:50:20 INFO - 1499161820730 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
02:50:20 INFO - 1499161820732 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}]
02:50:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499161820735 Marionette TRACE 358 <- [1,7,null,{"value":null}]
02:50:20 INFO - 1499161820736 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
02:50:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 218ms
02:50:20 INFO - TEST-START | test_click.py TestClick.test_container_element
02:50:20 INFO - 1499161820738 Marionette TRACE 358 <- [1,8,null,{}]
02:50:20 INFO - 1499161820740 Marionette DEBUG Closed connection 358
02:50:20 INFO - 1499161820741 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49827
02:50:20 INFO - 1499161820742 Marionette DEBUG Closed connection 359
02:50:20 INFO - 1499161820742 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49828
02:50:20 INFO - 1499161820744 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:20 INFO - 1499161820744 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:20 INFO - 1499161820747 Marionette DEBUG Register listener.js for window 4294967297
02:50:20 INFO - 1499161820751 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3f71b9aa-b950-4ebd-95c0-fdadc4ed5fbe","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:20 INFO - 1499161820753 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
02:50:20 INFO - 1499161820753 Marionette TRACE 360 <- [1,2,null,{}]
02:50:20 INFO - 1499161820754 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:20 INFO - 1499161820755 Marionette TRACE 360 <- [1,3,null,{}]
02:50:20 INFO - 1499161820756 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:20 INFO - 1499161820756 Marionette TRACE 360 <- [1,4,null,{}]
02:50:20 INFO - 1499161820758 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}]
02:50:20 INFO - TEST-START: test_click.py TestClick.test_container_element1499161820762 Marionette TRACE 360 <- [1,5,null,{"value":null}]
02:50:20 INFO - 1499161820763 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
02:50:20 INFO - 1499161820765 Marionette TRACE 360 <- [1,6,null,{}]
02:50:20 INFO - 1499161820766 Marionette DEBUG Closed connection 360
02:50:20 INFO - 1499161820767 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49829
02:50:20 INFO - 1499161820768 Marionette DEBUG Closed connection 361
02:50:20 INFO - 1499161820769 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49830
02:50:20 INFO - 1499161820770 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:20 INFO - 1499161820771 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:20 INFO - 1499161820774 Marionette DEBUG Register listener.js for window 4294967297
02:50:20 INFO - 1499161820778 Marionette TRACE 362 <- [1,1,null,{"sessionId":"15f39e96-80d2-43b7-86b1-70b0f47595dd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:20 INFO - 1499161820779 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
02:50:20 INFO - 1499161820781 Marionette TRACE 362 <- [1,2,null,{}]
02:50:20 INFO - 1499161820783 Marionette DEBUG Closed connection 362
02:50:20 INFO - 1499161820783 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49831
02:50:20 INFO - 1499161820784 Marionette DEBUG Closed connection 363
02:50:20 INFO - 1499161820785 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49832
02:50:20 INFO - 1499161820786 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:20 INFO - 1499161820787 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:20 INFO - 1499161820790 Marionette DEBUG Register listener.js for window 4294967297
02:50:20 INFO - 1499161820794 Marionette TRACE 364 <- [1,1,null,{"sessionId":"628c3a15-131a-417a-968c-4942929feb0d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:20 INFO - 1499161820795 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"}]
02:50:20 INFO - 1499161820803 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49373/test.html"
02:50:20 INFO - 1499161820806 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49373/test.html"
02:50:20 INFO - 1499161820806 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49373/test.html"
02:50:20 INFO - 1499161820814 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"
02:50:20 INFO - 1499161820819 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"
02:50:20 INFO - 1499161820831 Marionette TRACE 364 <- [1,2,null,{}]
02:50:20 INFO - 1499161820837 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:50:20 INFO - 1499161820845 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6880139a-4cc4-4949-b80a-95da2f70fd32","ELEMENT":"6880139a-4cc4-4949-b80a-95da2f70fd32"}}]
02:50:20 INFO - 1499161820847 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6880139a-4cc4-4949-b80a-95da2f70fd32"}]
02:50:21 INFO - 1499161821094 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:21 INFO - 1499161821095 Marionette TRACE 364 <- [1,4,null,{}]
02:50:21 INFO - 1499161821097 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6880139a-4cc4-4949-b80a-95da2f70fd32","name":"selected"}]
02:50:21 INFO - 1499161821102 Marionette TRACE 364 <- [1,5,null,{"value":true}]
02:50:21 INFO - 1499161821104 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}]
02:50:21 INFO - TEST-END: test_click.py TestClick.test_container_element1499161821108 Marionette TRACE 364 <- [1,6,null,{"value":null}]
02:50:21 INFO - 1499161821109 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
02:50:21 INFO - 1499161821110 Marionette TRACE 364 <- [1,7,null,{}]
02:50:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 372ms
02:50:21 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
02:50:21 INFO - 1499161821112 Marionette DEBUG Closed connection 364
02:50:21 INFO - 1499161821113 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49833
02:50:21 INFO - 1499161821114 Marionette DEBUG Closed connection 365
02:50:21 INFO - 1499161821115 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49834
02:50:21 INFO - 1499161821116 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:21 INFO - 1499161821117 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821120 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821124 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fb6e0635-8c37-48ea-8c19-594dec7fb9b9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821125 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
02:50:21 INFO - 1499161821125 Marionette TRACE 366 <- [1,2,null,{}]
02:50:21 INFO - 1499161821126 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:21 INFO - 1499161821127 Marionette TRACE 366 <- [1,3,null,{}]
02:50:21 INFO - 1499161821128 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:21 INFO - 1499161821128 Marionette TRACE 366 <- [1,4,null,{}]
02:50:21 INFO - 1499161821130 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}]
02:50:21 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499161821134 Marionette TRACE 366 <- [1,5,null,{"value":null}]
02:50:21 INFO - 1499161821135 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
02:50:21 INFO - 1499161821137 Marionette TRACE 366 <- [1,6,null,{}]
02:50:21 INFO - 1499161821138 Marionette DEBUG Closed connection 366
02:50:21 INFO - 1499161821139 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49835
02:50:21 INFO - 1499161821140 Marionette DEBUG Closed connection 367
02:50:21 INFO - 1499161821141 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49836
02:50:21 INFO - 1499161821142 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:21 INFO - 1499161821143 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821146 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821150 Marionette TRACE 368 <- [1,1,null,{"sessionId":"eae8b243-0824-4041-a873-555f89584c97","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821151 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
02:50:21 INFO - 1499161821153 Marionette TRACE 368 <- [1,2,null,{}]
02:50:21 INFO - 1499161821155 Marionette DEBUG Closed connection 368
02:50:21 INFO - 1499161821156 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49837
02:50:21 INFO - 1499161821157 Marionette DEBUG Closed connection 369
02:50:21 INFO - 1499161821157 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49838
02:50:21 INFO - 1499161821159 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:21 INFO - 1499161821159 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821163 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821166 Marionette TRACE 370 <- [1,1,null,{"sessionId":"70838be3-29f9-4684-a77c-6c472df7799f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821168 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"}]
02:50:21 INFO - 1499161821170 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"
02:50:21 INFO - 1499161821172 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"
02:50:21 INFO - 1499161821173 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"
02:50:21 INFO - 1499161821183 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"
02:50:21 INFO - 1499161821187 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"
02:50:21 INFO - 1499161821195 Marionette TRACE 370 <- [1,2,null,{}]
02:50:21 INFO - 1499161821200 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:50:21 INFO - 1499161821214 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eb158296-2ed9-46b9-965a-d9d9abdfbeee","ELEMENT":"eb158296-2ed9-46b9-965a-d9d9abdfbeee"}}]
02:50:21 INFO - 1499161821215 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"eb158296-2ed9-46b9-965a-d9d9abdfbeee"}]
02:50:21 INFO - 1499161821468 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:21 INFO - 1499161821469 Marionette TRACE 370 <- [1,4,null,{}]
02:50:21 INFO - 1499161821470 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"eb158296-2ed9-46b9-965a-d9d9abdfbeee","name":"selected"}]
02:50:21 INFO - 1499161821475 Marionette TRACE 370 <- [1,5,null,{"value":true}]
02:50:21 INFO - 1499161821477 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}]
02:50:21 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499161821481 Marionette TRACE 370 <- [1,6,null,{"value":null}]
02:50:21 INFO - 1499161821482 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
02:50:21 INFO - 1499161821483 Marionette TRACE 370 <- [1,7,null,{}]
02:50:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 372ms
02:50:21 INFO - TEST-START | test_click.py TestClick.test_css_transforms
02:50:21 INFO - 1499161821485 Marionette DEBUG Closed connection 370
02:50:21 INFO - 1499161821486 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49839
02:50:21 INFO - 1499161821487 Marionette DEBUG Closed connection 371
02:50:21 INFO - 1499161821488 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49840
02:50:21 INFO - 1499161821489 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:21 INFO - 1499161821490 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821493 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821497 Marionette TRACE 372 <- [1,1,null,{"sessionId":"dd9c2890-dd48-4027-9394-98b51b93f1a9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821498 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
02:50:21 INFO - 1499161821499 Marionette TRACE 372 <- [1,2,null,{}]
02:50:21 INFO - 1499161821500 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:21 INFO - 1499161821500 Marionette TRACE 372 <- [1,3,null,{}]
02:50:21 INFO - 1499161821501 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:21 INFO - 1499161821502 Marionette TRACE 372 <- [1,4,null,{}]
02:50:21 INFO - 1499161821504 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}]
02:50:21 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499161821507 Marionette TRACE 372 <- [1,5,null,{"value":null}]
02:50:21 INFO - 1499161821508 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
02:50:21 INFO - 1499161821510 Marionette TRACE 372 <- [1,6,null,{}]
02:50:21 INFO - 1499161821512 Marionette DEBUG Closed connection 372
02:50:21 INFO - 1499161821513 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49841
02:50:21 INFO - 1499161821514 Marionette DEBUG Closed connection 373
02:50:21 INFO - 1499161821514 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49842
02:50:21 INFO - 1499161821515 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:21 INFO - 1499161821516 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821519 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821523 Marionette TRACE 374 <- [1,1,null,{"sessionId":"de25d906-d8d0-4c6a-aac3-7bc0a3e6710e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821525 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
02:50:21 INFO - 1499161821526 Marionette TRACE 374 <- [1,2,null,{}]
02:50:21 INFO - 1499161821528 Marionette DEBUG Closed connection 374
02:50:21 INFO - 1499161821529 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49843
02:50:21 INFO - 1499161821530 Marionette DEBUG Closed connection 375
02:50:21 INFO - 1499161821530 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49844
02:50:21 INFO - 1499161821532 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:21 INFO - 1499161821532 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821536 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821539 Marionette TRACE 376 <- [1,1,null,{"sessionId":"3b181730-2325-49f0-a4a0-d7e2cafa1cf8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821540 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"}]
02:50:21 INFO - 1499161821543 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"
02:50:21 INFO - 1499161821545 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"
02:50:21 INFO - 1499161821545 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"
02:50:21 INFO - 1499161821552 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"
02:50:21 INFO - 1499161821555 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"
02:50:21 INFO - 1499161821566 Marionette TRACE 376 <- [1,2,null,{}]
02:50:21 INFO - 1499161821571 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
02:50:21 INFO - 1499161821578 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ecc71f78-a73f-4b05-8659-f0ddbed28f65","ELEMENT":"ecc71f78-a73f-4b05-8659-f0ddbed28f65"}}]
02:50:21 INFO - 1499161821580 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"ecc71f78-a73f-4b05-8659-f0ddbed28f65"}]
02:50:21 INFO - 1499161821827 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:21 INFO - 1499161821828 Marionette TRACE 376 <- [1,4,null,{}]
02:50:21 INFO - 1499161821830 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}]
02:50:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499161821833 Marionette TRACE 376 <- [1,5,null,{"value":null}]
02:50:21 INFO - 1499161821834 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
02:50:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 353ms
02:50:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
02:50:21 INFO - 1499161821836 Marionette TRACE 376 <- [1,6,null,{}]
02:50:21 INFO - 1499161821838 Marionette DEBUG Closed connection 376
02:50:21 INFO - 1499161821839 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49845
02:50:21 INFO - 1499161821840 Marionette DEBUG Closed connection 377
02:50:21 INFO - 1499161821841 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49846
02:50:21 INFO - 1499161821842 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:21 INFO - 1499161821842 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821846 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821849 Marionette TRACE 378 <- [1,1,null,{"sessionId":"134b791c-47fe-44f4-ad80-49ccc94c19a6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821850 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
02:50:21 INFO - 1499161821851 Marionette TRACE 378 <- [1,2,null,{}]
02:50:21 INFO - 1499161821852 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:21 INFO - 1499161821852 Marionette TRACE 378 <- [1,3,null,{}]
02:50:21 INFO - 1499161821853 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:21 INFO - 1499161821854 Marionette TRACE 378 <- [1,4,null,{}]
02:50:21 INFO - 1499161821856 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}]
02:50:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499161821859 Marionette TRACE 378 <- [1,5,null,{"value":null}]
02:50:21 INFO - 1499161821860 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
02:50:21 INFO - 1499161821862 Marionette TRACE 378 <- [1,6,null,{}]
02:50:21 INFO - 1499161821864 Marionette DEBUG Closed connection 378
02:50:21 INFO - 1499161821864 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49847
02:50:21 INFO - 1499161821865 Marionette DEBUG Closed connection 379
02:50:21 INFO - 1499161821866 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49848
02:50:21 INFO - 1499161821867 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:21 INFO - 1499161821868 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821871 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821874 Marionette TRACE 380 <- [1,1,null,{"sessionId":"ae38552f-ad48-40f9-b383-c7ac92e4f35c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821876 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
02:50:21 INFO - 1499161821877 Marionette TRACE 380 <- [1,2,null,{}]
02:50:21 INFO - 1499161821879 Marionette DEBUG Closed connection 380
02:50:21 INFO - 1499161821880 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49849
02:50:21 INFO - 1499161821880 Marionette DEBUG Closed connection 381
02:50:21 INFO - 1499161821881 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49850
02:50:21 INFO - 1499161821882 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:21 INFO - 1499161821883 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:21 INFO - 1499161821886 Marionette DEBUG Register listener.js for window 4294967297
02:50:21 INFO - 1499161821889 Marionette TRACE 382 <- [1,1,null,{"sessionId":"682c3ed5-1807-4fb3-944a-242a4af51cf2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:21 INFO - 1499161821892 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"}]
02:50:21 INFO - 1499161821894 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"
02:50:21 INFO - 1499161821896 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"
02:50:21 INFO - 1499161821896 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"
02:50:21 INFO - 1499161821904 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"
02:50:21 INFO - 1499161821909 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"
02:50:21 INFO - 1499161821916 Marionette TRACE 382 <- [1,2,null,{}]
02:50:21 INFO - 1499161821920 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
02:50:21 INFO - 1499161821928 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b7d5d88f-c4a5-4442-9053-e5df86c1c564","ELEMENT":"b7d5d88f-c4a5-4442-9053-e5df86c1c564"}}]
02:50:21 INFO - 1499161821929 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"b7d5d88f-c4a5-4442-9053-e5df86c1c564"}]
02:50:22 INFO - 1499161822180 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:22 INFO - 1499161822181 Marionette TRACE 382 <- [1,4,null,{}]
02:50:22 INFO - 1499161822183 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}]
02:50:22 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499161822186 Marionette TRACE 382 <- [1,5,null,{"value":null}]
02:50:22 INFO - 1499161822187 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
02:50:22 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 353ms
02:50:22 INFO - TEST-START | test_click.py TestClick.test_input_file
02:50:22 INFO - 1499161822189 Marionette TRACE 382 <- [1,6,null,{}]
02:50:22 INFO - 1499161822191 Marionette DEBUG Closed connection 382
02:50:22 INFO - 1499161822192 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49851
02:50:22 INFO - 1499161822193 Marionette DEBUG Closed connection 383
02:50:22 INFO - 1499161822194 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49852
02:50:22 INFO - 1499161822195 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:22 INFO - 1499161822195 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822199 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822212 Marionette TRACE 384 <- [1,1,null,{"sessionId":"31d7042d-6a59-4b1e-880c-4179e72985d0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822213 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
02:50:22 INFO - 1499161822214 Marionette TRACE 384 <- [1,2,null,{}]
02:50:22 INFO - 1499161822215 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:22 INFO - 1499161822215 Marionette TRACE 384 <- [1,3,null,{}]
02:50:22 INFO - 1499161822216 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:22 INFO - 1499161822217 Marionette TRACE 384 <- [1,4,null,{}]
02:50:22 INFO - 1499161822219 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}]
02:50:22 INFO - TEST-START: test_click.py TestClick.test_input_file1499161822222 Marionette TRACE 384 <- [1,5,null,{"value":null}]
02:50:22 INFO - 1499161822224 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
02:50:22 INFO - 1499161822225 Marionette TRACE 384 <- [1,6,null,{}]
02:50:22 INFO - 1499161822227 Marionette DEBUG Closed connection 384
02:50:22 INFO - 1499161822228 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49853
02:50:22 INFO - 1499161822229 Marionette DEBUG Closed connection 385
02:50:22 INFO - 1499161822230 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49854
02:50:22 INFO - 1499161822231 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:22 INFO - 1499161822231 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822235 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822238 Marionette TRACE 386 <- [1,1,null,{"sessionId":"2c997599-89ee-43b4-8174-b5bbe8898d19","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822240 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
02:50:22 INFO - 1499161822241 Marionette TRACE 386 <- [1,2,null,{}]
02:50:22 INFO - 1499161822243 Marionette DEBUG Closed connection 386
02:50:22 INFO - 1499161822244 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49855
02:50:22 INFO - 1499161822245 Marionette DEBUG Closed connection 387
02:50:22 INFO - 1499161822245 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49856
02:50:22 INFO - 1499161822246 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:22 INFO - 1499161822247 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822250 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822254 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f7bdde8c-a5b6-4b64-b5f0-b19fa3ff99bd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822255 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
02:50:22 INFO - 1499161822257 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"
02:50:22 INFO - 1499161822260 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"
02:50:22 INFO - 1499161822260 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"
02:50:22 INFO - 1499161822272 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:22 INFO - 1499161822278 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:22 INFO - 1499161822286 Marionette TRACE 388 <- [1,2,null,{}]
02:50:22 INFO - 1499161822295 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
02:50:22 INFO - 1499161822299 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86d9f4fb-7be3-4e8a-8000-21ddc702c458","ELEMENT":"86d9f4fb-7be3-4e8a-8000-21ddc702c458"}}]
02:50:22 INFO - 1499161822300 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"86d9f4fb-7be3-4e8a-8000-21ddc702c458"}]
02:50:22 INFO - 1499161822330 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]
02:50:22 INFO - 1499161822332 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}]
02:50:22 INFO - TEST-END: test_click.py TestClick.test_input_file1499161822335 Marionette TRACE 388 <- [1,5,null,{"value":null}]
02:50:22 INFO - 1499161822336 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
02:50:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 148ms
02:50:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
02:50:22 INFO - 1499161822338 Marionette TRACE 388 <- [1,6,null,{}]
02:50:22 INFO - 1499161822339 Marionette DEBUG Closed connection 388
02:50:22 INFO - 1499161822340 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49857
02:50:22 INFO - 1499161822341 Marionette DEBUG Closed connection 389
02:50:22 INFO - 1499161822342 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49858
02:50:22 INFO - 1499161822343 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:22 INFO - 1499161822344 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822347 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822350 Marionette TRACE 390 <- [1,1,null,{"sessionId":"e64010ad-62cd-4450-854d-7a085543334e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822351 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
02:50:22 INFO - 1499161822352 Marionette TRACE 390 <- [1,2,null,{}]
02:50:22 INFO - 1499161822353 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:22 INFO - 1499161822353 Marionette TRACE 390 <- [1,3,null,{}]
02:50:22 INFO - 1499161822354 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:22 INFO - 1499161822355 Marionette TRACE 390 <- [1,4,null,{}]
02:50:22 INFO - 1499161822357 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}]
02:50:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499161822360 Marionette TRACE 390 <- [1,5,null,{"value":null}]
02:50:22 INFO - 1499161822361 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
02:50:22 INFO - 1499161822363 Marionette TRACE 390 <- [1,6,null,{}]
02:50:22 INFO - 1499161822364 Marionette DEBUG Closed connection 390
02:50:22 INFO - 1499161822365 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49859
02:50:22 INFO - 1499161822366 Marionette DEBUG Closed connection 391
02:50:22 INFO - 1499161822367 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49860
02:50:22 INFO - 1499161822368 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:22 INFO - 1499161822369 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822372 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822375 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a4f51644-42b2-41d9-809e-76bcd7a671d2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822377 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
02:50:22 INFO - 1499161822378 Marionette TRACE 392 <- [1,2,null,{}]
02:50:22 INFO - 1499161822380 Marionette DEBUG Closed connection 392
02:50:22 INFO - 1499161822381 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49861
02:50:22 INFO - 1499161822382 Marionette DEBUG Closed connection 393
02:50:22 INFO - 1499161822382 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49862
02:50:22 INFO - 1499161822383 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:22 INFO - 1499161822384 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822387 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822391 Marionette TRACE 394 <- [1,1,null,{"sessionId":"86f9db92-07af-47cb-9ff1-9ba943761447","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822392 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"}]
02:50:22 INFO - 1499161822394 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:22 INFO - 1499161822396 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:22 INFO - 1499161822397 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:22 INFO - 1499161822404 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"
02:50:22 INFO - 1499161822408 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"
02:50:22 INFO - 1499161822417 Marionette TRACE 394 <- [1,2,null,{}]
02:50:22 INFO - 1499161822421 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
02:50:22 INFO - 1499161822428 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7115d472-c538-405d-90e8-8da6587cb395","ELEMENT":"7115d472-c538-405d-90e8-8da6587cb395"}}]
02:50:22 INFO - 1499161822430 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
02:50:22 INFO - 1499161822433 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a211d803-2bc3-4025-9100-e1b66f441c13","ELEMENT":"a211d803-2bc3-4025-9100-e1b66f441c13"}}]
02:50:22 INFO - 1499161822434 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7115d472-c538-405d-90e8-8da6587cb395"}]
02:50:22 INFO - 1499161822677 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:22 INFO - 1499161822678 Marionette TRACE 394 <- [1,5,null,{}]
02:50:22 INFO - 1499161822679 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a211d803-2bc3-4025-9100-e1b66f441c13"}]
02:50:22 INFO - 1499161822699 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
02:50:22 INFO - 1499161822701 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
02:50:22 INFO - 1499161822704 Marionette TRACE 394 <- [1,7,null,{"value":false}]
02:50:22 INFO - 1499161822706 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}]
02:50:22 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499161822709 Marionette TRACE 394 <- [1,8,null,{"value":null}]
02:50:22 INFO - 1499161822710 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
02:50:22 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 374ms
02:50:22 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
02:50:22 INFO - 1499161822712 Marionette TRACE 394 <- [1,9,null,{}]
02:50:22 INFO - 1499161822714 Marionette DEBUG Closed connection 394
02:50:22 INFO - 1499161822715 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49863
02:50:22 INFO - 1499161822715 Marionette DEBUG Closed connection 395
02:50:22 INFO - 1499161822716 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49864
02:50:22 INFO - 1499161822718 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:22 INFO - 1499161822718 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822721 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822725 Marionette TRACE 396 <- [1,1,null,{"sessionId":"020245e6-7d02-47e9-bf58-e3d385f16140","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822727 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
02:50:22 INFO - 1499161822727 Marionette TRACE 396 <- [1,2,null,{}]
02:50:22 INFO - 1499161822728 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:22 INFO - 1499161822729 Marionette TRACE 396 <- [1,3,null,{}]
02:50:22 INFO - 1499161822730 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:22 INFO - 1499161822730 Marionette TRACE 396 <- [1,4,null,{}]
02:50:22 INFO - 1499161822732 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}]
02:50:22 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499161822736 Marionette TRACE 396 <- [1,5,null,{"value":null}]
02:50:22 INFO - 1499161822737 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
02:50:22 INFO - 1499161822738 Marionette TRACE 396 <- [1,6,null,{}]
02:50:22 INFO - 1499161822740 Marionette DEBUG Closed connection 396
02:50:22 INFO - 1499161822741 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49865
02:50:22 INFO - 1499161822742 Marionette DEBUG Closed connection 397
02:50:22 INFO - 1499161822743 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49866
02:50:22 INFO - 1499161822744 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:22 INFO - 1499161822745 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822748 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822751 Marionette TRACE 398 <- [1,1,null,{"sessionId":"de4bf8f9-a35b-4af1-b31f-a926d4087d9f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822753 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
02:50:22 INFO - 1499161822754 Marionette TRACE 398 <- [1,2,null,{}]
02:50:22 INFO - 1499161822756 Marionette DEBUG Closed connection 398
02:50:22 INFO - 1499161822757 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49867
02:50:22 INFO - 1499161822758 Marionette DEBUG Closed connection 399
02:50:22 INFO - 1499161822759 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49868
02:50:22 INFO - 1499161822760 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:22 INFO - 1499161822760 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}
02:50:22 INFO - 1499161822764 Marionette DEBUG Register listener.js for window 4294967297
02:50:22 INFO - 1499161822767 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a562f0c8-1e15-421a-aa5f-15b8451d89b1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4nnqde.mozrunner","moz:accessibilityChecks":false}}]
02:50:22 INFO - 1499161822769 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"}]
02:50:22 INFO - 1499161822771 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"
02:50:22 INFO - 1499161822773 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"
02:50:22 INFO - 1499161822773 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"
02:50:22 INFO - 1499161822781 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"
02:50:22 INFO - 1499161822784 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"
02:50:22 INFO - 1499161822787 Marionette TRACE 400 <- [1,2,null,{}]
02:50:22 INFO - 1499161822798 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
02:50:22 INFO - 1499161822803 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4806305a-dd39-4e74-a34d-394afe8844da","ELEMENT":"4806305a-dd39-4e74-a34d-394afe8844da"}}]
02:50:22 INFO - 1499161822804 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"4806305a-dd39-4e74-a34d-394afe8844da"}]
02:50:22 INFO - 1499161822833 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
02:50:22 INFO - 1499161822835 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"4806305a-dd39-4e74-a34d-394afe8844da"}]
02:50:22 INFO - 1499161822853 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element