could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
17:13:20 INFO - 1499991200541 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}]
17:13:20 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499991200544 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:13:20 INFO - 1499991200546 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:13:20 INFO - 1499991200548 Marionette TRACE 352 <- [1,6,null,{}]
17:13:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 183ms
17:13:20 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:13:20 INFO - 1499991200550 Marionette DEBUG Closed connection 352
17:13:20 INFO - 1499991200551 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50766
17:13:20 INFO - 1499991200554 Marionette DEBUG Closed connection 353
17:13:20 INFO - 1499991200554 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50767
17:13:20 INFO - 1499991200556 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:20 INFO - 1499991200564 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:20 INFO - 1499991200567 Marionette DEBUG Register listener.js for window 15
17:13:20 INFO - 1499991200571 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b54460a0-4b64-49ad-b377-bb98e5c36cfc","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:20 INFO - 1499991200574 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:13:20 INFO - 1499991200575 Marionette TRACE 354 <- [1,2,null,{}]
17:13:20 INFO - 1499991200576 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:13:20 INFO - 1499991200576 Marionette TRACE 354 <- [1,3,null,{}]
17:13:20 INFO - 1499991200577 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:13:20 INFO - 1499991200578 Marionette TRACE 354 <- [1,4,null,{}]
17:13:20 INFO - 1499991200580 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}]
17:13:20 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499991200583 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:13:20 INFO - 1499991200584 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:13:20 INFO - 1499991200586 Marionette TRACE 354 <- [1,6,null,{}]
17:13:20 INFO - 1499991200589 Marionette DEBUG Closed connection 354
17:13:20 INFO - 1499991200590 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50768
17:13:20 INFO - 1499991200590 Marionette DEBUG Closed connection 355
17:13:20 INFO - 1499991200592 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50769
17:13:20 INFO - 1499991200593 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:20 INFO - 1499991200594 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:20 INFO - 1499991200597 Marionette DEBUG Register listener.js for window 15
17:13:20 INFO - 1499991200600 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5ffb3def-901b-4dd5-9f4a-869d6eb1e87b","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:20 INFO - 1499991200604 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:13:20 INFO - 1499991200606 Marionette TRACE 356 <- [1,2,null,{}]
17:13:20 INFO - 1499991200608 Marionette DEBUG Closed connection 356
17:13:20 INFO - 1499991200609 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50770
17:13:20 INFO - 1499991200611 Marionette DEBUG Closed connection 357
17:13:20 INFO - 1499991200612 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50771
17:13:20 INFO - 1499991200613 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:13:20 INFO - 1499991200614 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:20 INFO - 1499991200617 Marionette DEBUG Register listener.js for window 15
17:13:20 INFO - 1499991200621 Marionette TRACE 358 <- [1,1,null,{"sessionId":"85bfd032-5e20-4bb5-a3ac-89ece0538f8b","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:20 INFO - 1499991200623 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:50321/clicks.html"}]
17:13:20 INFO - 1499991200625 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50321/hidden.html"
17:13:20 INFO - 1499991200632 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50321/hidden.html"
17:13:20 INFO - 1499991200632 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50321/hidden.html"
17:13:20 INFO - 1499991200661 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50321/clicks.html"
17:13:20 INFO - 1499991200663 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50321/clicks.html"
17:13:20 INFO - 1499991200668 Marionette TRACE 358 <- [1,2,null,{}]
17:13:20 INFO - 1499991200677 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:13:20 INFO - 1499991200680 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e7486d01-790d-422e-833a-7ff0f96571cd","ELEMENT":"e7486d01-790d-422e-833a-7ff0f96571cd"}}]
17:13:20 INFO - 1499991200681 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"e7486d01-790d-422e-833a-7ff0f96571cd"}]
17:13:20 INFO - 1499991200729 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50321/clicks.html"
17:13:20 INFO - 1499991200743 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50321/clicks.html"
17:13:20 INFO - 1499991200743 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50321/clicks.html"
17:13:20 INFO - 1499991200781 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50321/test.html"
17:13:20 INFO - 1499991200785 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50321/test.html"
17:13:20 INFO - 1499991200800 Marionette TRACE 358 <- [1,4,null,{}]
17:13:20 INFO - 1499991200805 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:13:20 INFO - 1499991200808 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4dd5e168-1a95-480c-94cc-f0419e406768","ELEMENT":"4dd5e168-1a95-480c-94cc-f0419e406768"}}]
17:13:20 INFO - 1499991200809 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:13:20 INFO - 1499991200810 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:13:20 INFO - 1499991200812 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}]
17:13:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499991200815 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:13:20 INFO - 1499991200816 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:13:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 269ms
17:13:20 INFO - TEST-START | test_click.py TestClick.test_container_element
17:13:20 INFO - 1499991200818 Marionette TRACE 358 <- [1,8,null,{}]
17:13:20 INFO - 1499991200820 Marionette DEBUG Closed connection 358
17:13:20 INFO - 1499991200821 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50774
17:13:20 INFO - 1499991200822 Marionette DEBUG Closed connection 359
17:13:20 INFO - 1499991200822 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50775
17:13:20 INFO - 1499991200824 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:20 INFO - 1499991200824 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:20 INFO - 1499991200827 Marionette DEBUG Register listener.js for window 15
17:13:20 INFO - 1499991200831 Marionette TRACE 360 <- [1,1,null,{"sessionId":"ba4f5aa2-16a5-47c7-8f29-94d913cea71f","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:20 INFO - 1499991200833 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:13:20 INFO - 1499991200834 Marionette TRACE 360 <- [1,2,null,{}]
17:13:20 INFO - 1499991200835 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:13:20 INFO - 1499991200836 Marionette TRACE 360 <- [1,3,null,{}]
17:13:20 INFO - 1499991200836 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:13:20 INFO - 1499991200837 Marionette TRACE 360 <- [1,4,null,{}]
17:13:20 INFO - 1499991200839 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}]
17:13:20 INFO - TEST-START: test_click.py TestClick.test_container_element1499991200842 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:13:20 INFO - 1499991200843 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:13:20 INFO - 1499991200845 Marionette TRACE 360 <- [1,6,null,{}]
17:13:20 INFO - 1499991200847 Marionette DEBUG Closed connection 360
17:13:20 INFO - 1499991200848 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50776
17:13:20 INFO - 1499991200849 Marionette DEBUG Closed connection 361
17:13:20 INFO - 1499991200850 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50777
17:13:20 INFO - 1499991200851 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:20 INFO - 1499991200851 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:20 INFO - 1499991200854 Marionette DEBUG Register listener.js for window 15
17:13:20 INFO - 1499991200858 Marionette TRACE 362 <- [1,1,null,{"sessionId":"9579227d-759b-4913-a54a-efe3fd5a6e8e","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:20 INFO - 1499991200860 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:13:20 INFO - 1499991200862 Marionette TRACE 362 <- [1,2,null,{}]
17:13:20 INFO - 1499991200864 Marionette DEBUG Closed connection 362
17:13:20 INFO - 1499991200865 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50778
17:13:20 INFO - 1499991200866 Marionette DEBUG Closed connection 363
17:13:20 INFO - 1499991200867 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50779
17:13:20 INFO - 1499991200868 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:13:20 INFO - 1499991200869 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:20 INFO - 1499991200872 Marionette DEBUG Register listener.js for window 15
17:13:20 INFO - 1499991200875 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1c9fa32f-1e0d-46ac-916e-4038f51207e6","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:20 INFO - 1499991200878 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"}]
17:13:20 INFO - 1499991200880 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50321/test.html"
17:13:20 INFO - 1499991200883 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50321/test.html"
17:13:20 INFO - 1499991200883 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50321/test.html"
17:13:20 INFO - 1499991200915 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"
17:13:20 INFO - 1499991200921 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"
17:13:20 INFO - 1499991200934 Marionette TRACE 364 <- [1,2,null,{}]
17:13:20 INFO - 1499991200939 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:13:20 INFO - 1499991200942 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb22a162-25b8-48fa-bbb0-ab502a2d1eea","ELEMENT":"fb22a162-25b8-48fa-bbb0-ab502a2d1eea"}}]
17:13:20 INFO - 1499991200944 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"fb22a162-25b8-48fa-bbb0-ab502a2d1eea"}]
17:13:21 INFO - 1499991201190 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:13:21 INFO - 1499991201191 Marionette TRACE 364 <- [1,4,null,{}]
17:13:21 INFO - 1499991201192 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"fb22a162-25b8-48fa-bbb0-ab502a2d1eea","name":"selected"}]
17:13:21 INFO - 1499991201197 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:13:21 INFO - 1499991201199 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}]
17:13:21 INFO - TEST-END: test_click.py TestClick.test_container_element1499991201203 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:13:21 INFO - 1499991201206 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:13:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 390ms
17:13:21 INFO - 1499991201208 Marionette TRACE 364 <- [1,7,null,{}]
17:13:21 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:13:21 INFO - 1499991201210 Marionette DEBUG Closed connection 364
17:13:21 INFO - 1499991201212 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50780
17:13:21 INFO - 1499991201212 Marionette DEBUG Closed connection 365
17:13:21 INFO - 1499991201213 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50781
17:13:21 INFO - 1499991201214 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:21 INFO - 1499991201215 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:21 INFO - 1499991201218 Marionette DEBUG Register listener.js for window 15
17:13:21 INFO - 1499991201230 Marionette TRACE 366 <- [1,1,null,{"sessionId":"6905dc17-7282-45e3-b42c-0149366d7c49","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:21 INFO - 1499991201232 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:13:21 INFO - 1499991201233 Marionette TRACE 366 <- [1,2,null,{}]
17:13:21 INFO - 1499991201234 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:13:21 INFO - 1499991201234 Marionette TRACE 366 <- [1,3,null,{}]
17:13:21 INFO - 1499991201235 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:13:21 INFO - 1499991201236 Marionette TRACE 366 <- [1,4,null,{}]
17:13:21 INFO - 1499991201238 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}]
17:13:21 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499991201241 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:13:21 INFO - 1499991201242 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:13:21 INFO - 1499991201244 Marionette TRACE 366 <- [1,6,null,{}]
17:13:21 INFO - 1499991201246 Marionette DEBUG Closed connection 366
17:13:21 INFO - 1499991201247 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50782
17:13:21 INFO - 1499991201248 Marionette DEBUG Closed connection 367
17:13:21 INFO - 1499991201248 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50783
17:13:21 INFO - 1499991201250 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:21 INFO - 1499991201250 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:21 INFO - 1499991201253 Marionette DEBUG Register listener.js for window 15
17:13:21 INFO - 1499991201257 Marionette TRACE 368 <- [1,1,null,{"sessionId":"63c09365-e72d-4c4d-a020-3e0970887ec7","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:21 INFO - 1499991201259 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:13:21 INFO - 1499991201261 Marionette TRACE 368 <- [1,2,null,{}]
17:13:21 INFO - 1499991201263 Marionette DEBUG Closed connection 368
17:13:21 INFO - 1499991201264 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50784
17:13:21 INFO - 1499991201265 Marionette DEBUG Closed connection 369
17:13:21 INFO - 1499991201266 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50785
17:13:21 INFO - 1499991201267 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:13:21 INFO - 1499991201268 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:21 INFO - 1499991201271 Marionette DEBUG Register listener.js for window 15
17:13:21 INFO - 1499991201275 Marionette TRACE 370 <- [1,1,null,{"sessionId":"399adcce-8e54-4891-8d07-2996c210bd30","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:21 INFO - 1499991201279 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"}]
17:13:21 INFO - 1499991201282 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"
17:13:21 INFO - 1499991201285 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"
17:13:21 INFO - 1499991201286 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"
17:13:21 INFO - 1499991201308 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"
17:13:21 INFO - 1499991201312 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"
17:13:21 INFO - 1499991201317 Marionette TRACE 370 <- [1,2,null,{}]
17:13:21 INFO - 1499991201325 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:13:21 INFO - 1499991201328 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"675b7354-b8e1-4e55-b8f4-b1af28112199","ELEMENT":"675b7354-b8e1-4e55-b8f4-b1af28112199"}}]
17:13:21 INFO - 1499991201330 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"675b7354-b8e1-4e55-b8f4-b1af28112199"}]
17:13:21 INFO - 1499991201578 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:13:21 INFO - 1499991201579 Marionette TRACE 370 <- [1,4,null,{}]
17:13:21 INFO - 1499991201580 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"675b7354-b8e1-4e55-b8f4-b1af28112199","name":"selected"}]
17:13:21 INFO - 1499991201585 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:13:21 INFO - 1499991201587 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}]
17:13:21 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499991201590 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:13:21 INFO - 1499991201591 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:13:21 INFO - 1499991201592 Marionette TRACE 370 <- [1,7,null,{}]
17:13:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 384ms
17:13:21 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:13:21 INFO - 1499991201595 Marionette DEBUG Closed connection 370
17:13:21 INFO - 1499991201595 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50786
17:13:21 INFO - 1499991201596 Marionette DEBUG Closed connection 371
17:13:21 INFO - 1499991201597 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50787
17:13:21 INFO - 1499991201598 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:21 INFO - 1499991201599 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:21 INFO - 1499991201602 Marionette DEBUG Register listener.js for window 15
17:13:21 INFO - 1499991201605 Marionette TRACE 372 <- [1,1,null,{"sessionId":"dcdcaaa7-a0a6-44af-bab3-e16165bfb6d8","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:21 INFO - 1499991201608 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:13:21 INFO - 1499991201608 Marionette TRACE 372 <- [1,2,null,{}]
17:13:21 INFO - 1499991201609 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:13:21 INFO - 1499991201610 Marionette TRACE 372 <- [1,3,null,{}]
17:13:21 INFO - 1499991201611 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:13:21 INFO - 1499991201611 Marionette TRACE 372 <- [1,4,null,{}]
17:13:21 INFO - 1499991201613 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}]
17:13:21 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499991201616 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:13:21 INFO - 1499991201617 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:13:21 INFO - 1499991201619 Marionette TRACE 372 <- [1,6,null,{}]
17:13:21 INFO - 1499991201621 Marionette DEBUG Closed connection 372
17:13:21 INFO - 1499991201622 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50788
17:13:21 INFO - 1499991201623 Marionette DEBUG Closed connection 373
17:13:21 INFO - 1499991201624 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50789
17:13:21 INFO - 1499991201625 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:21 INFO - 1499991201626 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:21 INFO - 1499991201629 Marionette DEBUG Register listener.js for window 15
17:13:21 INFO - 1499991201632 Marionette TRACE 374 <- [1,1,null,{"sessionId":"869380b9-0b6a-4950-bf1f-5ee8d2c5e3e5","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:21 INFO - 1499991201635 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:13:21 INFO - 1499991201636 Marionette TRACE 374 <- [1,2,null,{}]
17:13:21 INFO - 1499991201639 Marionette DEBUG Closed connection 374
17:13:21 INFO - 1499991201639 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50790
17:13:21 INFO - 1499991201640 Marionette DEBUG Closed connection 375
17:13:21 INFO - 1499991201641 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50791
17:13:21 INFO - 1499991201642 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:13:21 INFO - 1499991201643 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:21 INFO - 1499991201646 Marionette DEBUG Register listener.js for window 15
17:13:21 INFO - 1499991201649 Marionette TRACE 376 <- [1,1,null,{"sessionId":"205ccab5-bb48-4afe-b087-3064b58f3208","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:21 INFO - 1499991201654 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"}]
17:13:21 INFO - 1499991201656 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"
17:13:21 INFO - 1499991201658 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"
17:13:21 INFO - 1499991201659 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"
17:13:21 INFO - 1499991201682 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"
17:13:21 INFO - 1499991201685 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"
17:13:21 INFO - 1499991201697 Marionette TRACE 376 <- [1,2,null,{}]
17:13:21 INFO - 1499991201699 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:13:21 INFO - 1499991201702 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"134973cd-ee27-4a79-a2da-7c725e9095c7","ELEMENT":"134973cd-ee27-4a79-a2da-7c725e9095c7"}}]
17:13:21 INFO - 1499991201705 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"134973cd-ee27-4a79-a2da-7c725e9095c7"}]
17:13:21 INFO - 1499991201948 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:13:21 INFO - 1499991201949 Marionette TRACE 376 <- [1,4,null,{}]
17:13:21 INFO - 1499991201951 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}]
17:13:21 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499991201954 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:13:21 INFO - 1499991201955 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:13:21 INFO - 1499991201957 Marionette TRACE 376 <- [1,6,null,{}]
17:13:21 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 364ms
17:13:21 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:13:21 INFO - 1499991201960 Marionette DEBUG Closed connection 376
17:13:21 INFO - 1499991201961 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50792
17:13:21 INFO - 1499991201962 Marionette DEBUG Closed connection 377
17:13:21 INFO - 1499991201963 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50793
17:13:21 INFO - 1499991201964 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:21 INFO - 1499991201965 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:21 INFO - 1499991201968 Marionette DEBUG Register listener.js for window 15
17:13:21 INFO - 1499991201980 Marionette TRACE 378 <- [1,1,null,{"sessionId":"b3e6df3d-f923-4987-acc4-9968fd39e982","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:21 INFO - 1499991201983 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:13:21 INFO - 1499991201983 Marionette TRACE 378 <- [1,2,null,{}]
17:13:21 INFO - 1499991201984 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:13:21 INFO - 1499991201985 Marionette TRACE 378 <- [1,3,null,{}]
17:13:21 INFO - 1499991201986 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:13:21 INFO - 1499991201986 Marionette TRACE 378 <- [1,4,null,{}]
17:13:21 INFO - 1499991201988 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}]
17:13:21 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499991201992 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:13:21 INFO - 1499991201993 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:13:21 INFO - 1499991201994 Marionette TRACE 378 <- [1,6,null,{}]
17:13:22 INFO - 1499991201997 Marionette DEBUG Closed connection 378
17:13:22 INFO - 1499991201998 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50794
17:13:22 INFO - 1499991201998 Marionette DEBUG Closed connection 379
17:13:22 INFO - 1499991201999 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50795
17:13:22 INFO - 1499991202000 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:22 INFO - 1499991202001 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202004 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202008 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d6f40d1f-a188-4626-b3aa-bf63c4f62d44","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202010 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:13:22 INFO - 1499991202012 Marionette TRACE 380 <- [1,2,null,{}]
17:13:22 INFO - 1499991202014 Marionette DEBUG Closed connection 380
17:13:22 INFO - 1499991202015 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50796
17:13:22 INFO - 1499991202016 Marionette DEBUG Closed connection 381
17:13:22 INFO - 1499991202016 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50797
17:13:22 INFO - 1499991202018 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:13:22 INFO - 1499991202018 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202021 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202024 Marionette TRACE 382 <- [1,1,null,{"sessionId":"8c6edb1e-9656-409e-ac4d-02c18cc8fa14","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202027 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"}]
17:13:22 INFO - 1499991202028 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"
17:13:22 INFO - 1499991202031 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"
17:13:22 INFO - 1499991202032 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"
17:13:22 INFO - 1499991202052 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"
17:13:22 INFO - 1499991202059 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"
17:13:22 INFO - 1499991202063 Marionette TRACE 382 <- [1,2,null,{}]
17:13:22 INFO - 1499991202065 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:13:22 INFO - 1499991202068 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3926e7bc-b3f8-49cf-a733-147ebd490753","ELEMENT":"3926e7bc-b3f8-49cf-a733-147ebd490753"}}]
17:13:22 INFO - 1499991202077 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"3926e7bc-b3f8-49cf-a733-147ebd490753"}]
17:13:22 INFO - 1499991202324 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:13:22 INFO - 1499991202325 Marionette TRACE 382 <- [1,4,null,{}]
17:13:22 INFO - 1499991202327 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}]
17:13:22 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499991202330 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:13:22 INFO - 1499991202331 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:13:22 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 374ms
17:13:22 INFO - 1499991202333 Marionette TRACE 382 <- [1,6,null,{}]
17:13:22 INFO - TEST-START | test_click.py TestClick.test_input_file
17:13:22 INFO - 1499991202336 Marionette DEBUG Closed connection 382
17:13:22 INFO - 1499991202337 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50798
17:13:22 INFO - 1499991202338 Marionette DEBUG Closed connection 383
17:13:22 INFO - 1499991202339 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50799
17:13:22 INFO - 1499991202340 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:22 INFO - 1499991202340 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202343 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202347 Marionette TRACE 384 <- [1,1,null,{"sessionId":"c70c5b50-2cbc-4e00-8d65-9390170e98c3","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202350 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:13:22 INFO - 1499991202350 Marionette TRACE 384 <- [1,2,null,{}]
17:13:22 INFO - 1499991202351 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:13:22 INFO - 1499991202352 Marionette TRACE 384 <- [1,3,null,{}]
17:13:22 INFO - 1499991202353 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:13:22 INFO - 1499991202354 Marionette TRACE 384 <- [1,4,null,{}]
17:13:22 INFO - 1499991202355 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}]
17:13:22 INFO - TEST-START: test_click.py TestClick.test_input_file1499991202359 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:13:22 INFO - 1499991202360 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:13:22 INFO - 1499991202361 Marionette TRACE 384 <- [1,6,null,{}]
17:13:22 INFO - 1499991202364 Marionette DEBUG Closed connection 384
17:13:22 INFO - 1499991202364 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50800
17:13:22 INFO - 1499991202365 Marionette DEBUG Closed connection 385
17:13:22 INFO - 1499991202366 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50801
17:13:22 INFO - 1499991202367 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:22 INFO - 1499991202368 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202371 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202374 Marionette TRACE 386 <- [1,1,null,{"sessionId":"293dc424-0123-44a8-b730-7c4b54d761e0","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202377 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:13:22 INFO - 1499991202378 Marionette TRACE 386 <- [1,2,null,{}]
17:13:22 INFO - 1499991202381 Marionette DEBUG Closed connection 386
17:13:22 INFO - 1499991202382 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50802
17:13:22 INFO - 1499991202383 Marionette DEBUG Closed connection 387
17:13:22 INFO - 1499991202384 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50803
17:13:22 INFO - 1499991202385 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:13:22 INFO - 1499991202386 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202389 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202392 Marionette TRACE 388 <- [1,1,null,{"sessionId":"2da98cec-21a0-43be-9260-7a52e48cc0fa","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202395 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:13:22 INFO - 1499991202397 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"
17:13:22 INFO - 1499991202400 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"
17:13:22 INFO - 1499991202400 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"
17:13:22 INFO - 1499991202424 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:13:22 INFO - 1499991202428 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:13:22 INFO - 1499991202432 Marionette TRACE 388 <- [1,2,null,{}]
17:13:22 INFO - 1499991202434 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:13:22 INFO - 1499991202445 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"77b8d37f-5f9f-4968-bc0f-cb49c71a9e6e","ELEMENT":"77b8d37f-5f9f-4968-bc0f-cb49c71a9e6e"}}]
17:13:22 INFO - 1499991202447 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"77b8d37f-5f9f-4968-bc0f-cb49c71a9e6e"}]
17:13:22 INFO - 1499991202477 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
17:13:22 INFO - 1499991202479 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}]
17:13:22 INFO - TEST-END: test_click.py TestClick.test_input_file1499991202482 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:13:22 INFO - 1499991202483 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:13:22 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 151ms
17:13:22 INFO - 1499991202485 Marionette TRACE 388 <- [1,6,null,{}]
17:13:22 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:13:22 INFO - 1499991202488 Marionette DEBUG Closed connection 388
17:13:22 INFO - 1499991202489 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50804
17:13:22 INFO - 1499991202490 Marionette DEBUG Closed connection 389
17:13:22 INFO - 1499991202490 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50805
17:13:22 INFO - 1499991202492 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:22 INFO - 1499991202492 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202495 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202499 Marionette TRACE 390 <- [1,1,null,{"sessionId":"7ccf7380-7524-4399-abd8-f5a7337e4cad","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202502 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:13:22 INFO - 1499991202502 Marionette TRACE 390 <- [1,2,null,{}]
17:13:22 INFO - 1499991202503 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:13:22 INFO - 1499991202504 Marionette TRACE 390 <- [1,3,null,{}]
17:13:22 INFO - 1499991202505 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:13:22 INFO - 1499991202506 Marionette TRACE 390 <- [1,4,null,{}]
17:13:22 INFO - 1499991202507 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}]
17:13:22 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499991202511 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:13:22 INFO - 1499991202512 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:13:22 INFO - 1499991202513 Marionette TRACE 390 <- [1,6,null,{}]
17:13:22 INFO - 1499991202516 Marionette DEBUG Closed connection 390
17:13:22 INFO - 1499991202517 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50806
17:13:22 INFO - 1499991202517 Marionette DEBUG Closed connection 391
17:13:22 INFO - 1499991202518 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50807
17:13:22 INFO - 1499991202519 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:22 INFO - 1499991202520 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202523 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202535 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d39b2424-ef2e-42ea-b2e8-fe622efd4bb3","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202537 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:13:22 INFO - 1499991202539 Marionette TRACE 392 <- [1,2,null,{}]
17:13:22 INFO - 1499991202541 Marionette DEBUG Closed connection 392
17:13:22 INFO - 1499991202542 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50808
17:13:22 INFO - 1499991202543 Marionette DEBUG Closed connection 393
17:13:22 INFO - 1499991202544 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50809
17:13:22 INFO - 1499991202545 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:13:22 INFO - 1499991202545 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202548 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202552 Marionette TRACE 394 <- [1,1,null,{"sessionId":"607a8f65-8279-4dc7-97c8-ea3a5d4e7582","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202554 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"}]
17:13:22 INFO - 1499991202556 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:13:22 INFO - 1499991202559 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:13:22 INFO - 1499991202559 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:13:22 INFO - 1499991202584 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"
17:13:22 INFO - 1499991202586 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"
17:13:22 INFO - 1499991202598 Marionette TRACE 394 <- [1,2,null,{}]
17:13:22 INFO - 1499991202600 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:13:22 INFO - 1499991202603 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"33a6878a-a460-48af-abd7-61a31fad6492","ELEMENT":"33a6878a-a460-48af-abd7-61a31fad6492"}}]
17:13:22 INFO - 1499991202604 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:13:22 INFO - 1499991202607 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0d8d1429-84af-4b15-811a-06c17d3da8c5","ELEMENT":"0d8d1429-84af-4b15-811a-06c17d3da8c5"}}]
17:13:22 INFO - 1499991202608 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"33a6878a-a460-48af-abd7-61a31fad6492"}]
17:13:22 INFO - 1499991202856 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:13:22 INFO - 1499991202857 Marionette TRACE 394 <- [1,5,null,{}]
17:13:22 INFO - 1499991202858 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"0d8d1429-84af-4b15-811a-06c17d3da8c5"}]
17:13:22 INFO - 1499991202877 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
17:13:22 INFO - 1499991202879 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:13:22 INFO - 1499991202882 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:13:22 INFO - 1499991202884 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}]
17:13:22 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499991202887 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:13:22 INFO - 1499991202888 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:13:22 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 404ms
17:13:22 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:13:22 INFO - 1499991202890 Marionette TRACE 394 <- [1,9,null,{}]
17:13:22 INFO - 1499991202892 Marionette DEBUG Closed connection 394
17:13:22 INFO - 1499991202893 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50810
17:13:22 INFO - 1499991202894 Marionette DEBUG Closed connection 395
17:13:22 INFO - 1499991202895 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50811
17:13:22 INFO - 1499991202896 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:22 INFO - 1499991202896 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202899 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202903 Marionette TRACE 396 <- [1,1,null,{"sessionId":"423e1857-d85d-45b3-b23f-c7869008ca7e","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202905 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:13:22 INFO - 1499991202906 Marionette TRACE 396 <- [1,2,null,{}]
17:13:22 INFO - 1499991202907 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:13:22 INFO - 1499991202908 Marionette TRACE 396 <- [1,3,null,{}]
17:13:22 INFO - 1499991202908 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:13:22 INFO - 1499991202909 Marionette TRACE 396 <- [1,4,null,{}]
17:13:22 INFO - 1499991202911 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}]
17:13:22 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499991202914 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:13:22 INFO - 1499991202915 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:13:22 INFO - 1499991202917 Marionette TRACE 396 <- [1,6,null,{}]
17:13:22 INFO - 1499991202919 Marionette DEBUG Closed connection 396
17:13:22 INFO - 1499991202920 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50812
17:13:22 INFO - 1499991202921 Marionette DEBUG Closed connection 397
17:13:22 INFO - 1499991202921 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50813
17:13:22 INFO - 1499991202923 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:13:22 INFO - 1499991202923 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202926 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202930 Marionette TRACE 398 <- [1,1,null,{"sessionId":"9f9016ea-e761-4fbc-8761-3e0d524fead3","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202932 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:13:22 INFO - 1499991202934 Marionette TRACE 398 <- [1,2,null,{}]
17:13:22 INFO - 1499991202936 Marionette DEBUG Closed connection 398
17:13:22 INFO - 1499991202937 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50814
17:13:22 INFO - 1499991202938 Marionette DEBUG Closed connection 399
17:13:22 INFO - 1499991202939 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50815
17:13:22 INFO - 1499991202941 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:13:22 INFO - 1499991202941 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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}
17:13:22 INFO - 1499991202944 Marionette DEBUG Register listener.js for window 15
17:13:22 INFO - 1499991202948 Marionette TRACE 400 <- [1,1,null,{"sessionId":"244e1c0b-269d-41de-8d06-465a25de224c","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":1932,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp5jsjvq.mozrunner","moz:accessibilityChecks":false}}]
17:13:22 INFO - 1499991202951 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"}]
17:13:22 INFO - 1499991202953 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"
17:13:22 INFO - 1499991202955 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"
17:13:22 INFO - 1499991202956 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"
17:13:22 INFO - 1499991202980 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"
17:13:22 INFO - 1499991202982 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"
17:13:22 INFO - 1499991202986 Marionette TRACE 400 <- [1,2,null,{}]
17:13:23 INFO - 1499991202996 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:13:23 INFO - 1499991202999 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9246b7b7-5298-4509-9a1f-ccc2c44caa14","ELEMENT":"9246b7b7-5298-4509-9a1f-ccc2c44caa14"}}]
17:13:23 INFO - 1499991203001 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9246b7b7-5298-4509-9a1f-ccc2c44caa14"}]
17:13:23 INFO - 1499991203026 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:13:23 INFO - 1499991203027 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9246b7b7-5298-4509-9a1f-ccc2c44caa14"}]
17:13:23 INFO - 1499991203046 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element