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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
19:19:02 INFO - 1500689942477 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}]
19:19:02 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500689942480 Marionette TRACE 352 <- [1,5,null,{"value":null}]
19:19:02 INFO - 1500689942481 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
19:19:02 INFO - 1500689942483 Marionette TRACE 352 <- [1,6,null,{}]
19:19:02 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 204ms
19:19:02 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:19:02 INFO - 1500689942485 Marionette DEBUG Closed connection 352
19:19:02 INFO - 1500689942486 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49848
19:19:02 INFO - 1500689942487 Marionette DEBUG Closed connection 353
19:19:02 INFO - 1500689942488 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49849
19:19:02 INFO - 1500689942489 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:02 INFO - 1500689942490 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:02 INFO - 1500689942493 Marionette DEBUG Register listener.js for window 15
19:19:02 INFO - 1500689942504 Marionette TRACE 354 <- [1,1,null,{"sessionId":"8d4fbef0-edd7-471d-b0df-9cba644eb549","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:02 INFO - 1500689942506 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
19:19:02 INFO - 1500689942507 Marionette TRACE 354 <- [1,2,null,{}]
19:19:02 INFO - 1500689942508 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:19:02 INFO - 1500689942509 Marionette TRACE 354 <- [1,3,null,{}]
19:19:02 INFO - 1500689942509 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
19:19:02 INFO - 1500689942510 Marionette TRACE 354 <- [1,4,null,{}]
19:19:02 INFO - 1500689942512 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}]
19:19:02 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500689942515 Marionette TRACE 354 <- [1,5,null,{"value":null}]
19:19:02 INFO - 1500689942516 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
19:19:02 INFO - 1500689942518 Marionette TRACE 354 <- [1,6,null,{}]
19:19:02 INFO - 1500689942521 Marionette DEBUG Closed connection 354
19:19:02 INFO - 1500689942521 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49850
19:19:02 INFO - 1500689942522 Marionette DEBUG Closed connection 355
19:19:02 INFO - 1500689942523 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49851
19:19:02 INFO - 1500689942524 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:02 INFO - 1500689942525 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:02 INFO - 1500689942528 Marionette DEBUG Register listener.js for window 15
19:19:02 INFO - 1500689942531 Marionette TRACE 356 <- [1,1,null,{"sessionId":"fc94d1ac-0296-4080-8f55-30290b0c78c4","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:02 INFO - 1500689942533 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
19:19:02 INFO - 1500689942535 Marionette TRACE 356 <- [1,2,null,{}]
19:19:02 INFO - 1500689942537 Marionette DEBUG Closed connection 356
19:19:02 INFO - 1500689942538 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49852
19:19:02 INFO - 1500689942539 Marionette DEBUG Closed connection 357
19:19:02 INFO - 1500689942539 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49853
19:19:02 INFO - 1500689942540 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:19:02 INFO - 1500689942541 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:02 INFO - 1500689942544 Marionette DEBUG Register listener.js for window 15
19:19:02 INFO - 1500689942560 Marionette TRACE 358 <- [1,1,null,{"sessionId":"b2bdf747-dfe7-4dc4-892f-7e3a679e8d8d","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:02 INFO - 1500689942583 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49416/clicks.html"}]
19:19:02 INFO - 1500689942585 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49416/hidden.html"
19:19:02 INFO - 1500689942591 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49416/hidden.html"
19:19:02 INFO - 1500689942592 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49416/hidden.html"
19:19:02 INFO - 1500689942619 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49416/clicks.html"
19:19:02 INFO - 1500689942621 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49416/clicks.html"
19:19:02 INFO - 1500689942626 Marionette TRACE 358 <- [1,2,null,{}]
19:19:02 INFO - 1500689942635 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:19:02 INFO - 1500689942638 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"88c79f8f-07dc-4301-9b9e-414d2310d85d","ELEMENT":"88c79f8f-07dc-4301-9b9e-414d2310d85d"}}]
19:19:02 INFO - 1500689942650 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"88c79f8f-07dc-4301-9b9e-414d2310d85d"}]
19:19:02 INFO - 1500689942694 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49416/clicks.html"
19:19:02 INFO - 1500689942706 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49416/clicks.html"
19:19:02 INFO - 1500689942706 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49416/clicks.html"
19:19:02 INFO - 1500689942741 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49416/test.html"
19:19:02 INFO - 1500689942746 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49416/test.html"
19:19:02 INFO - 1500689942760 Marionette TRACE 358 <- [1,4,null,{}]
19:19:02 INFO - 1500689942762 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:19:02 INFO - 1500689942766 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0dd930d1-4e93-409b-a6b7-4d492cc3aae7","ELEMENT":"0dd930d1-4e93-409b-a6b7-4d492cc3aae7"}}]
19:19:02 INFO - 1500689942767 Marionette TRACE 358 -> [0,6,"getTitle",{}]
19:19:02 INFO - 1500689942768 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
19:19:02 INFO - 1500689942770 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}]
19:19:02 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500689942773 Marionette TRACE 358 <- [1,7,null,{"value":null}]
19:19:02 INFO - 1500689942774 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
19:19:02 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 292ms
19:19:02 INFO - TEST-START | test_click.py TestClick.test_container_element
19:19:02 INFO - 1500689942776 Marionette TRACE 358 <- [1,8,null,{}]
19:19:02 INFO - 1500689942779 Marionette DEBUG Closed connection 358
19:19:02 INFO - 1500689942780 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49856
19:19:02 INFO - 1500689942781 Marionette DEBUG Closed connection 359
19:19:02 INFO - 1500689942781 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49857
19:19:02 INFO - 1500689942783 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:02 INFO - 1500689942783 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:02 INFO - 1500689942786 Marionette DEBUG Register listener.js for window 15
19:19:02 INFO - 1500689942790 Marionette TRACE 360 <- [1,1,null,{"sessionId":"f28631c6-0930-4ae2-8581-ca4071fb1160","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:02 INFO - 1500689942792 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
19:19:02 INFO - 1500689942792 Marionette TRACE 360 <- [1,2,null,{}]
19:19:02 INFO - 1500689942793 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:19:02 INFO - 1500689942794 Marionette TRACE 360 <- [1,3,null,{}]
19:19:02 INFO - 1500689942795 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
19:19:02 INFO - 1500689942796 Marionette TRACE 360 <- [1,4,null,{}]
19:19:02 INFO - 1500689942798 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}]
19:19:02 INFO - TEST-START: test_click.py TestClick.test_container_element1500689942800 Marionette TRACE 360 <- [1,5,null,{"value":null}]
19:19:02 INFO - 1500689942801 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
19:19:02 INFO - 1500689942803 Marionette TRACE 360 <- [1,6,null,{}]
19:19:02 INFO - 1500689942805 Marionette DEBUG Closed connection 360
19:19:02 INFO - 1500689942806 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49858
19:19:02 INFO - 1500689942807 Marionette DEBUG Closed connection 361
19:19:02 INFO - 1500689942808 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49859
19:19:02 INFO - 1500689942809 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:02 INFO - 1500689942809 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:02 INFO - 1500689942812 Marionette DEBUG Register listener.js for window 15
19:19:02 INFO - 1500689942815 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d8d11802-6b4f-4f5c-a7c7-e6b7dab24cdf","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:02 INFO - 1500689942818 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
19:19:02 INFO - 1500689942819 Marionette TRACE 362 <- [1,2,null,{}]
19:19:02 INFO - 1500689942821 Marionette DEBUG Closed connection 362
19:19:02 INFO - 1500689942822 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49860
19:19:02 INFO - 1500689942823 Marionette DEBUG Closed connection 363
19:19:02 INFO - 1500689942824 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49861
19:19:02 INFO - 1500689942825 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:19:02 INFO - 1500689942825 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:02 INFO - 1500689942828 Marionette DEBUG Register listener.js for window 15
19:19:02 INFO - 1500689942831 Marionette TRACE 364 <- [1,1,null,{"sessionId":"dec2b99e-050a-45d6-b11a-85b885accd38","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:02 INFO - 1500689942834 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"}]
19:19:02 INFO - 1500689942835 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49416/test.html"
19:19:02 INFO - 1500689942838 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49416/test.html"
19:19:02 INFO - 1500689942838 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49416/test.html"
19:19:02 INFO - 1500689942867 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"
19:19:02 INFO - 1500689942871 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"
19:19:02 INFO - 1500689942875 Marionette TRACE 364 <- [1,2,null,{}]
19:19:02 INFO - 1500689942883 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:19:02 INFO - 1500689942887 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d406f695-7d4d-44f9-bcfe-b863a82329bc","ELEMENT":"d406f695-7d4d-44f9-bcfe-b863a82329bc"}}]
19:19:02 INFO - 1500689942889 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d406f695-7d4d-44f9-bcfe-b863a82329bc"}]
19:19:03 INFO - 1500689943133 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:19:03 INFO - 1500689943134 Marionette TRACE 364 <- [1,4,null,{}]
19:19:03 INFO - 1500689943136 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d406f695-7d4d-44f9-bcfe-b863a82329bc","name":"selected"}]
19:19:03 INFO - 1500689943140 Marionette TRACE 364 <- [1,5,null,{"value":true}]
19:19:03 INFO - 1500689943142 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}]
19:19:03 INFO - TEST-END: test_click.py TestClick.test_container_element1500689943147 Marionette TRACE 364 <- [1,6,null,{"value":null}]
19:19:03 INFO - 1500689943148 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
19:19:03 INFO - 1500689943150 Marionette TRACE 364 <- [1,7,null,{}]
19:19:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 373ms
19:19:03 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:19:03 INFO - 1500689943152 Marionette DEBUG Closed connection 364
19:19:03 INFO - 1500689943153 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49864
19:19:03 INFO - 1500689943154 Marionette DEBUG Closed connection 365
19:19:03 INFO - 1500689943155 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49865
19:19:03 INFO - 1500689943156 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:03 INFO - 1500689943156 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:03 INFO - 1500689943159 Marionette DEBUG Register listener.js for window 15
19:19:03 INFO - 1500689943170 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fe932f16-a092-48ea-9837-0dbd7fd87aae","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:03 INFO - 1500689943173 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
19:19:03 INFO - 1500689943174 Marionette TRACE 366 <- [1,2,null,{}]
19:19:03 INFO - 1500689943175 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:19:03 INFO - 1500689943175 Marionette TRACE 366 <- [1,3,null,{}]
19:19:03 INFO - 1500689943176 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
19:19:03 INFO - 1500689943177 Marionette TRACE 366 <- [1,4,null,{}]
19:19:03 INFO - 1500689943179 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}]
19:19:03 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500689943182 Marionette TRACE 366 <- [1,5,null,{"value":null}]
19:19:03 INFO - 1500689943183 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
19:19:03 INFO - 1500689943184 Marionette TRACE 366 <- [1,6,null,{}]
19:19:03 INFO - 1500689943187 Marionette DEBUG Closed connection 366
19:19:03 INFO - 1500689943187 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49866
19:19:03 INFO - 1500689943188 Marionette DEBUG Closed connection 367
19:19:03 INFO - 1500689943189 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49867
19:19:03 INFO - 1500689943190 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:03 INFO - 1500689943191 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:03 INFO - 1500689943194 Marionette DEBUG Register listener.js for window 15
19:19:03 INFO - 1500689943197 Marionette TRACE 368 <- [1,1,null,{"sessionId":"89960f1e-e731-447d-a133-cb8c06485a35","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:03 INFO - 1500689943199 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
19:19:03 INFO - 1500689943201 Marionette TRACE 368 <- [1,2,null,{}]
19:19:03 INFO - 1500689943203 Marionette DEBUG Closed connection 368
19:19:03 INFO - 1500689943204 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49868
19:19:03 INFO - 1500689943205 Marionette DEBUG Closed connection 369
19:19:03 INFO - 1500689943205 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49869
19:19:03 INFO - 1500689943206 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:19:03 INFO - 1500689943207 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:03 INFO - 1500689943210 Marionette DEBUG Register listener.js for window 15
19:19:03 INFO - 1500689943213 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a575b917-1aed-463c-87cc-8cd9585832ca","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:03 INFO - 1500689943215 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"}]
19:19:03 INFO - 1500689943218 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"
19:19:03 INFO - 1500689943220 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"
19:19:03 INFO - 1500689943221 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"
19:19:03 INFO - 1500689943241 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"
19:19:03 INFO - 1500689943244 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"
19:19:03 INFO - 1500689943255 Marionette TRACE 370 <- [1,2,null,{}]
19:19:03 INFO - 1500689943256 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:19:03 INFO - 1500689943259 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a05162c6-c819-46ca-aa4f-b1f2b5407cf7","ELEMENT":"a05162c6-c819-46ca-aa4f-b1f2b5407cf7"}}]
19:19:03 INFO - 1500689943260 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a05162c6-c819-46ca-aa4f-b1f2b5407cf7"}]
19:19:03 INFO - 1500689943506 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:19:03 INFO - 1500689943507 Marionette TRACE 370 <- [1,4,null,{}]
19:19:03 INFO - 1500689943508 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a05162c6-c819-46ca-aa4f-b1f2b5407cf7","name":"selected"}]
19:19:03 INFO - 1500689943513 Marionette TRACE 370 <- [1,5,null,{"value":true}]
19:19:03 INFO - 1500689943515 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}]
19:19:03 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500689943518 Marionette TRACE 370 <- [1,6,null,{"value":null}]
19:19:03 INFO - 1500689943519 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
19:19:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 370ms
19:19:03 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:19:03 INFO - 1500689943521 Marionette TRACE 370 <- [1,7,null,{}]
19:19:03 INFO - 1500689943523 Marionette DEBUG Closed connection 370
19:19:03 INFO - 1500689943524 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49870
19:19:03 INFO - 1500689943525 Marionette DEBUG Closed connection 371
19:19:03 INFO - 1500689943525 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49871
19:19:03 INFO - 1500689943526 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:03 INFO - 1500689943527 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:03 INFO - 1500689943530 Marionette DEBUG Register listener.js for window 15
19:19:03 INFO - 1500689943533 Marionette TRACE 372 <- [1,1,null,{"sessionId":"e935c3d2-84e7-47c8-b610-a617ff365945","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:03 INFO - 1500689943536 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
19:19:03 INFO - 1500689943536 Marionette TRACE 372 <- [1,2,null,{}]
19:19:03 INFO - 1500689943537 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:19:03 INFO - 1500689943538 Marionette TRACE 372 <- [1,3,null,{}]
19:19:03 INFO - 1500689943538 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
19:19:03 INFO - 1500689943539 Marionette TRACE 372 <- [1,4,null,{}]
19:19:03 INFO - 1500689943541 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}]
19:19:03 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500689943544 Marionette TRACE 372 <- [1,5,null,{"value":null}]
19:19:03 INFO - 1500689943545 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
19:19:03 INFO - 1500689943546 Marionette TRACE 372 <- [1,6,null,{}]
19:19:03 INFO - 1500689943549 Marionette DEBUG Closed connection 372
19:19:03 INFO - 1500689943549 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49872
19:19:03 INFO - 1500689943550 Marionette DEBUG Closed connection 373
19:19:03 INFO - 1500689943551 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49873
19:19:03 INFO - 1500689943552 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:03 INFO - 1500689943553 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:03 INFO - 1500689943555 Marionette DEBUG Register listener.js for window 15
19:19:03 INFO - 1500689943559 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f7e41e11-a3a3-458b-9332-4455ea5fe251","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:03 INFO - 1500689943561 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
19:19:03 INFO - 1500689943563 Marionette TRACE 374 <- [1,2,null,{}]
19:19:03 INFO - 1500689943565 Marionette DEBUG Closed connection 374
19:19:03 INFO - 1500689943566 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49874
19:19:03 INFO - 1500689943566 Marionette DEBUG Closed connection 375
19:19:03 INFO - 1500689943579 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49875
19:19:03 INFO - 1500689943581 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:19:03 INFO - 1500689943584 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:03 INFO - 1500689943588 Marionette DEBUG Register listener.js for window 15
19:19:03 INFO - 1500689943594 Marionette TRACE 376 <- [1,1,null,{"sessionId":"3b776089-d3ec-4a6a-8d6c-653638e889a6","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:03 INFO - 1500689943642 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"}]
19:19:03 INFO - 1500689943648 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"
19:19:03 INFO - 1500689943651 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"
19:19:03 INFO - 1500689943652 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"
19:19:03 INFO - 1500689943678 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"
19:19:03 INFO - 1500689943681 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"
19:19:03 INFO - 1500689943695 Marionette TRACE 376 <- [1,2,null,{}]
19:19:03 INFO - 1500689943698 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:19:03 INFO - 1500689943703 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"322378d9-9ba0-4a02-a73b-0fdf61f519ea","ELEMENT":"322378d9-9ba0-4a02-a73b-0fdf61f519ea"}}]
19:19:03 INFO - 1500689943704 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"322378d9-9ba0-4a02-a73b-0fdf61f519ea"}]
19:19:03 INFO - 1500689943957 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:19:03 INFO - 1500689943958 Marionette TRACE 376 <- [1,4,null,{}]
19:19:03 INFO - 1500689943961 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}]
19:19:03 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500689943966 Marionette TRACE 376 <- [1,5,null,{"value":null}]
19:19:03 INFO - 1500689943972 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
19:19:03 INFO - 1500689943975 Marionette TRACE 376 <- [1,6,null,{}]
19:19:03 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 455ms
19:19:03 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:19:03 INFO - 1500689943978 Marionette DEBUG Closed connection 376
19:19:03 INFO - 1500689943979 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49876
19:19:03 INFO - 1500689943980 Marionette DEBUG Closed connection 377
19:19:03 INFO - 1500689943981 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49877
19:19:03 INFO - 1500689943983 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:03 INFO - 1500689943986 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:03 INFO - 1500689943989 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689943995 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f596140a-fc64-40db-b860-ef538f2a8e7e","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944001 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
19:19:04 INFO - 1500689944002 Marionette TRACE 378 <- [1,2,null,{}]
19:19:04 INFO - 1500689944022 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:19:04 INFO - 1500689944023 Marionette TRACE 378 <- [1,3,null,{}]
19:19:04 INFO - 1500689944074 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
19:19:04 INFO - 1500689944075 Marionette TRACE 378 <- [1,4,null,{}]
19:19:04 INFO - 1500689944103 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}]
19:19:04 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500689944108 Marionette TRACE 378 <- [1,5,null,{"value":null}]
19:19:04 INFO - 1500689944121 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
19:19:04 INFO - 1500689944123 Marionette TRACE 378 <- [1,6,null,{}]
19:19:04 INFO - 1500689944127 Marionette DEBUG Closed connection 378
19:19:04 INFO - 1500689944128 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49878
19:19:04 INFO - 1500689944129 Marionette DEBUG Closed connection 379
19:19:04 INFO - 1500689944130 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49879
19:19:04 INFO - 1500689944132 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:04 INFO - 1500689944133 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:04 INFO - 1500689944136 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689944141 Marionette TRACE 380 <- [1,1,null,{"sessionId":"064098fd-5d81-4fe7-a979-8684a8a69c78","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944144 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
19:19:04 INFO - 1500689944147 Marionette TRACE 380 <- [1,2,null,{}]
19:19:04 INFO - 1500689944149 Marionette DEBUG Closed connection 380
19:19:04 INFO - 1500689944150 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49880
19:19:04 INFO - 1500689944151 Marionette DEBUG Closed connection 381
19:19:04 INFO - 1500689944152 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49881
19:19:04 INFO - 1500689944155 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:19:04 INFO - 1500689944157 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:04 INFO - 1500689944160 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689944164 Marionette TRACE 382 <- [1,1,null,{"sessionId":"934798ff-d9b2-4a14-8ad8-7c604b797eaf","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944167 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"}]
19:19:04 INFO - 1500689944169 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"
19:19:04 INFO - 1500689944172 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"
19:19:04 INFO - 1500689944172 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"
19:19:04 INFO - 1500689944199 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"
19:19:04 INFO - 1500689944201 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"
19:19:04 INFO - 1500689944207 Marionette TRACE 382 <- [1,2,null,{}]
19:19:04 INFO - 1500689944208 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:19:04 INFO - 1500689944220 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0cb51d33-33e8-4e85-b27d-38e82b68d60b","ELEMENT":"0cb51d33-33e8-4e85-b27d-38e82b68d60b"}}]
19:19:04 INFO - 1500689944232 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"0cb51d33-33e8-4e85-b27d-38e82b68d60b"}]
19:19:04 INFO - 1500689944479 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:19:04 INFO - 1500689944481 Marionette TRACE 382 <- [1,4,null,{}]
19:19:04 INFO - 1500689944483 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}]
19:19:04 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500689944486 Marionette TRACE 382 <- [1,5,null,{"value":null}]
19:19:04 INFO - 1500689944487 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
19:19:04 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 514ms
19:19:04 INFO - TEST-START | test_click.py TestClick.test_input_file
19:19:04 INFO - 1500689944489 Marionette TRACE 382 <- [1,6,null,{}]
19:19:04 INFO - 1500689944492 Marionette DEBUG Closed connection 382
19:19:04 INFO - 1500689944493 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49882
19:19:04 INFO - 1500689944494 Marionette DEBUG Closed connection 383
19:19:04 INFO - 1500689944495 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49883
19:19:04 INFO - 1500689944496 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:04 INFO - 1500689944497 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:04 INFO - 1500689944500 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689944505 Marionette TRACE 384 <- [1,1,null,{"sessionId":"208893bb-d7aa-40a1-a264-2a63dc8e36e2","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944508 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
19:19:04 INFO - 1500689944508 Marionette TRACE 384 <- [1,2,null,{}]
19:19:04 INFO - 1500689944509 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:19:04 INFO - 1500689944510 Marionette TRACE 384 <- [1,3,null,{}]
19:19:04 INFO - 1500689944511 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
19:19:04 INFO - 1500689944512 Marionette TRACE 384 <- [1,4,null,{}]
19:19:04 INFO - 1500689944514 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}]
19:19:04 INFO - TEST-START: test_click.py TestClick.test_input_file1500689944517 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:19:04 INFO - 1500689944518 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:19:04 INFO - 1500689944520 Marionette TRACE 384 <- [1,6,null,{}]
19:19:04 INFO - 1500689944523 Marionette DEBUG Closed connection 384
19:19:04 INFO - 1500689944523 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49884
19:19:04 INFO - 1500689944524 Marionette DEBUG Closed connection 385
19:19:04 INFO - 1500689944525 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49885
19:19:04 INFO - 1500689944526 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:04 INFO - 1500689944528 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:04 INFO - 1500689944531 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689944535 Marionette TRACE 386 <- [1,1,null,{"sessionId":"9fe9eb00-2027-43ef-9005-a97c49ced414","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944538 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
19:19:04 INFO - 1500689944540 Marionette TRACE 386 <- [1,2,null,{}]
19:19:04 INFO - 1500689944543 Marionette DEBUG Closed connection 386
19:19:04 INFO - 1500689944545 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49886
19:19:04 INFO - 1500689944546 Marionette DEBUG Closed connection 387
19:19:04 INFO - 1500689944547 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49887
19:19:04 INFO - 1500689944548 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:19:04 INFO - 1500689944549 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:04 INFO - 1500689944552 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689944557 Marionette TRACE 388 <- [1,1,null,{"sessionId":"c854bdbb-f24a-4ac5-80c6-5dc8240c7dd7","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944559 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:19:04 INFO - 1500689944561 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"
19:19:04 INFO - 1500689944564 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"
19:19:04 INFO - 1500689944565 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"
19:19:04 INFO - 1500689944592 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:19:04 INFO - 1500689944596 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:19:04 INFO - 1500689944609 Marionette TRACE 388 <- [1,2,null,{}]
19:19:04 INFO - 1500689944610 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:19:04 INFO - 1500689944615 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"354e4eb9-c012-4671-986c-65114c3d5a6e","ELEMENT":"354e4eb9-c012-4671-986c-65114c3d5a6e"}}]
19:19:04 INFO - 1500689944616 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"354e4eb9-c012-4671-986c-65114c3d5a6e"}]
19:19:04 INFO - 1500689944646 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
19:19:04 INFO - 1500689944649 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}]
19:19:04 INFO - TEST-END: test_click.py TestClick.test_input_file1500689944653 Marionette TRACE 388 <- [1,5,null,{"value":null}]
19:19:04 INFO - 1500689944654 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
19:19:04 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 166ms
19:19:04 INFO - 1500689944656 Marionette TRACE 388 <- [1,6,null,{}]
19:19:04 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:19:04 INFO - 1500689944658 Marionette DEBUG Closed connection 388
19:19:04 INFO - 1500689944659 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49888
19:19:04 INFO - 1500689944660 Marionette DEBUG Closed connection 389
19:19:04 INFO - 1500689944661 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49889
19:19:04 INFO - 1500689944662 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:04 INFO - 1500689944663 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:04 INFO - 1500689944666 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689944677 Marionette TRACE 390 <- [1,1,null,{"sessionId":"5b0f8e93-daf6-4ead-832e-0d77eb4ad45c","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944679 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
19:19:04 INFO - 1500689944680 Marionette TRACE 390 <- [1,2,null,{}]
19:19:04 INFO - 1500689944681 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:19:04 INFO - 1500689944681 Marionette TRACE 390 <- [1,3,null,{}]
19:19:04 INFO - 1500689944682 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
19:19:04 INFO - 1500689944683 Marionette TRACE 390 <- [1,4,null,{}]
19:19:04 INFO - 1500689944685 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}]
19:19:04 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500689944689 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:19:04 INFO - 1500689944690 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:19:04 INFO - 1500689944691 Marionette TRACE 390 <- [1,6,null,{}]
19:19:04 INFO - 1500689944694 Marionette DEBUG Closed connection 390
19:19:04 INFO - 1500689944695 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49890
19:19:04 INFO - 1500689944696 Marionette DEBUG Closed connection 391
19:19:04 INFO - 1500689944697 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49891
19:19:04 INFO - 1500689944698 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:04 INFO - 1500689944700 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:04 INFO - 1500689944703 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689944706 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a31bf168-b63a-425d-a3f6-b30e123d6b9a","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944709 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
19:19:04 INFO - 1500689944710 Marionette TRACE 392 <- [1,2,null,{}]
19:19:04 INFO - 1500689944712 Marionette DEBUG Closed connection 392
19:19:04 INFO - 1500689944713 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49892
19:19:04 INFO - 1500689944714 Marionette DEBUG Closed connection 393
19:19:04 INFO - 1500689944715 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49893
19:19:04 INFO - 1500689944716 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:19:04 INFO - 1500689944717 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:04 INFO - 1500689944720 Marionette DEBUG Register listener.js for window 15
19:19:04 INFO - 1500689944723 Marionette TRACE 394 <- [1,1,null,{"sessionId":"88cf100d-e7ec-48cf-815c-ccea13a44a7e","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:04 INFO - 1500689944725 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"}]
19:19:04 INFO - 1500689944727 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:19:04 INFO - 1500689944730 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:19:04 INFO - 1500689944730 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:19:04 INFO - 1500689944759 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"
19:19:04 INFO - 1500689944762 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"
19:19:04 INFO - 1500689944773 Marionette TRACE 394 <- [1,2,null,{}]
19:19:04 INFO - 1500689944774 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:19:04 INFO - 1500689944778 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"793d5b1c-2c07-4c81-ab00-a472d575d914","ELEMENT":"793d5b1c-2c07-4c81-ab00-a472d575d914"}}]
19:19:04 INFO - 1500689944779 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:19:04 INFO - 1500689944782 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"89cf2806-dece-474f-b18f-1687371a22bf","ELEMENT":"89cf2806-dece-474f-b18f-1687371a22bf"}}]
19:19:04 INFO - 1500689944784 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"793d5b1c-2c07-4c81-ab00-a472d575d914"}]
19:19:05 INFO - 1500689945030 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:19:05 INFO - 1500689945032 Marionette TRACE 394 <- [1,5,null,{}]
19:19:05 INFO - 1500689945033 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"89cf2806-dece-474f-b18f-1687371a22bf"}]
19:19:05 INFO - 1500689945052 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
19:19:05 INFO - 1500689945054 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
19:19:05 INFO - 1500689945057 Marionette TRACE 394 <- [1,7,null,{"value":false}]
19:19:05 INFO - 1500689945059 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}]
19:19:05 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500689945063 Marionette TRACE 394 <- [1,8,null,{"value":null}]
19:19:05 INFO - 1500689945064 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
19:19:05 INFO - 1500689945065 Marionette TRACE 394 <- [1,9,null,{}]
19:19:05 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 409ms
19:19:05 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:19:05 INFO - 1500689945068 Marionette DEBUG Closed connection 394
19:19:05 INFO - 1500689945069 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49894
19:19:05 INFO - 1500689945070 Marionette DEBUG Closed connection 395
19:19:05 INFO - 1500689945070 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49895
19:19:05 INFO - 1500689945072 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:05 INFO - 1500689945072 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:05 INFO - 1500689945075 Marionette DEBUG Register listener.js for window 15
19:19:05 INFO - 1500689945079 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6a934eae-fe17-4fe5-ae1a-b37d674ed747","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:05 INFO - 1500689945081 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
19:19:05 INFO - 1500689945082 Marionette TRACE 396 <- [1,2,null,{}]
19:19:05 INFO - 1500689945083 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:19:05 INFO - 1500689945084 Marionette TRACE 396 <- [1,3,null,{}]
19:19:05 INFO - 1500689945085 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
19:19:05 INFO - 1500689945086 Marionette TRACE 396 <- [1,4,null,{}]
19:19:05 INFO - 1500689945087 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}]
19:19:05 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500689945090 Marionette TRACE 396 <- [1,5,null,{"value":null}]
19:19:05 INFO - 1500689945091 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
19:19:05 INFO - 1500689945093 Marionette TRACE 396 <- [1,6,null,{}]
19:19:05 INFO - 1500689945096 Marionette DEBUG Closed connection 396
19:19:05 INFO - 1500689945096 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49896
19:19:05 INFO - 1500689945097 Marionette DEBUG Closed connection 397
19:19:05 INFO - 1500689945098 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49897
19:19:05 INFO - 1500689945099 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:19:05 INFO - 1500689945100 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:05 INFO - 1500689945105 Marionette DEBUG Register listener.js for window 15
19:19:05 INFO - 1500689945110 Marionette TRACE 398 <- [1,1,null,{"sessionId":"0b473ded-18f3-4653-9fc3-9ef900f75302","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:05 INFO - 1500689945113 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
19:19:05 INFO - 1500689945114 Marionette TRACE 398 <- [1,2,null,{}]
19:19:05 INFO - 1500689945117 Marionette DEBUG Closed connection 398
19:19:05 INFO - 1500689945118 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49898
19:19:05 INFO - 1500689945118 Marionette DEBUG Closed connection 399
19:19:05 INFO - 1500689945119 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49899
19:19:05 INFO - 1500689945120 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:19:05 INFO - 1500689945121 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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}
19:19:05 INFO - 1500689945124 Marionette DEBUG Register listener.js for window 15
19:19:05 INFO - 1500689945127 Marionette TRACE 400 <- [1,1,null,{"sessionId":"95baf292-a42e-4f28-8b51-61c2d028ffd8","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":3760,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp4dof6n.mozrunner","moz:accessibilityChecks":false}}]
19:19:05 INFO - 1500689945132 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"}]
19:19:05 INFO - 1500689945133 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"
19:19:05 INFO - 1500689945136 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"
19:19:05 INFO - 1500689945136 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"
19:19:05 INFO - 1500689945160 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"
19:19:05 INFO - 1500689945163 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"
19:19:05 INFO - 1500689945175 Marionette TRACE 400 <- [1,2,null,{}]
19:19:05 INFO - 1500689945177 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:19:05 INFO - 1500689945181 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"397ccf28-a20d-4769-83a5-b2b66dadeb43","ELEMENT":"397ccf28-a20d-4769-83a5-b2b66dadeb43"}}]
19:19:05 INFO - 1500689945183 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"397ccf28-a20d-4769-83a5-b2b66dadeb43"}]
19:19:05 INFO - 1500689945206 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
19:19:05 INFO - 1500689945207 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"397ccf28-a20d-4769-83a5-b2b66dadeb43"}]
19:19:05 INFO - 1500689945225 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element