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]
22:02:29 INFO - 1499835749419 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}]
22:02:29 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499835749422 Marionette TRACE 352 <- [1,5,null,{"value":null}]
22:02:29 INFO - 1499835749423 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
22:02:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 147ms
22:02:29 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
22:02:29 INFO - 1499835749424 Marionette TRACE 352 <- [1,6,null,{}]
22:02:29 INFO - 1499835749426 Marionette DEBUG Closed connection 352
22:02:29 INFO - 1499835749427 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49846
22:02:29 INFO - 1499835749428 Marionette DEBUG Closed connection 353
22:02:29 INFO - 1499835749429 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49847
22:02:29 INFO - 1499835749430 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:29 INFO - 1499835749430 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:29 INFO - 1499835749433 Marionette DEBUG Register listener.js for window 4294967297
22:02:29 INFO - 1499835749437 Marionette TRACE 354 <- [1,1,null,{"sessionId":"73457107-6e7a-47af-a884-0af719eab044","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:29 INFO - 1499835749439 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
22:02:29 INFO - 1499835749439 Marionette TRACE 354 <- [1,2,null,{}]
22:02:29 INFO - 1499835749440 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:02:29 INFO - 1499835749441 Marionette TRACE 354 <- [1,3,null,{}]
22:02:29 INFO - 1499835749442 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
22:02:29 INFO - 1499835749442 Marionette TRACE 354 <- [1,4,null,{}]
22:02:29 INFO - 1499835749444 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}]
22:02:29 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499835749447 Marionette TRACE 354 <- [1,5,null,{"value":null}]
22:02:29 INFO - 1499835749448 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
22:02:29 INFO - 1499835749450 Marionette TRACE 354 <- [1,6,null,{}]
22:02:29 INFO - 1499835749451 Marionette DEBUG Closed connection 354
22:02:29 INFO - 1499835749452 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49848
22:02:29 INFO - 1499835749453 Marionette DEBUG Closed connection 355
22:02:29 INFO - 1499835749454 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49849
22:02:29 INFO - 1499835749455 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:29 INFO - 1499835749455 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:29 INFO - 1499835749458 Marionette DEBUG Register listener.js for window 4294967297
22:02:29 INFO - 1499835749462 Marionette TRACE 356 <- [1,1,null,{"sessionId":"7ffb2745-c63f-40c5-8712-6da8c048f5c4","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:29 INFO - 1499835749463 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
22:02:29 INFO - 1499835749464 Marionette TRACE 356 <- [1,2,null,{}]
22:02:29 INFO - 1499835749466 Marionette DEBUG Closed connection 356
22:02:29 INFO - 1499835749467 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49850
22:02:29 INFO - 1499835749468 Marionette DEBUG Closed connection 357
22:02:29 INFO - 1499835749468 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49851
22:02:29 INFO - 1499835749471 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:02:29 INFO - 1499835749471 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:29 INFO - 1499835749475 Marionette DEBUG Register listener.js for window 4294967297
22:02:29 INFO - 1499835749479 Marionette TRACE 358 <- [1,1,null,{"sessionId":"ae82b011-ba3c-483b-a109-83d7ab25f5c8","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:29 INFO - 1499835749480 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49396/clicks.html"}]
22:02:29 INFO - 1499835749482 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49396/hidden.html"
22:02:29 INFO - 1499835749492 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49396/hidden.html"
22:02:29 INFO - 1499835749493 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49396/hidden.html"
22:02:29 INFO - 1499835749500 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49396/clicks.html"
22:02:29 INFO - 1499835749506 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49396/clicks.html"
22:02:29 INFO - 1499835749520 Marionette TRACE 358 <- [1,2,null,{}]
22:02:29 INFO - 1499835749535 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
22:02:29 INFO - 1499835749544 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f2d26734-f07a-48fb-a247-348f326dd950","ELEMENT":"f2d26734-f07a-48fb-a247-348f326dd950"}}]
22:02:29 INFO - 1499835749546 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f2d26734-f07a-48fb-a247-348f326dd950"}]
22:02:29 INFO - 1499835749587 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49396/clicks.html"
22:02:29 INFO - 1499835749596 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49396/clicks.html"
22:02:29 INFO - 1499835749596 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49396/clicks.html"
22:02:29 INFO - 1499835749604 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49396/test.html"
22:02:29 INFO - 1499835749608 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49396/test.html"
22:02:29 INFO - 1499835749611 Marionette TRACE 358 <- [1,4,null,{}]
22:02:29 INFO - 1499835749622 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
22:02:29 INFO - 1499835749627 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3131b9e2-eef5-4d0e-b855-c05f990ca90b","ELEMENT":"3131b9e2-eef5-4d0e-b855-c05f990ca90b"}}]
22:02:29 INFO - 1499835749628 Marionette TRACE 358 -> [0,6,"getTitle",{}]
22:02:29 INFO - 1499835749628 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
22:02:29 INFO - 1499835749635 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}]
22:02:29 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499835749638 Marionette TRACE 358 <- [1,7,null,{"value":null}]
22:02:29 INFO - 1499835749639 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
22:02:29 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 216ms
22:02:29 INFO - TEST-START | test_click.py TestClick.test_container_element
22:02:29 INFO - 1499835749641 Marionette TRACE 358 <- [1,8,null,{}]
22:02:29 INFO - 1499835749642 Marionette DEBUG Closed connection 358
22:02:29 INFO - 1499835749643 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49854
22:02:29 INFO - 1499835749644 Marionette DEBUG Closed connection 359
22:02:29 INFO - 1499835749645 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49855
22:02:29 INFO - 1499835749646 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:29 INFO - 1499835749647 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:29 INFO - 1499835749650 Marionette DEBUG Register listener.js for window 4294967297
22:02:29 INFO - 1499835749654 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4c3d22b4-0b20-4141-9130-33fcb9165172","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:29 INFO - 1499835749655 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
22:02:29 INFO - 1499835749656 Marionette TRACE 360 <- [1,2,null,{}]
22:02:29 INFO - 1499835749657 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:02:29 INFO - 1499835749657 Marionette TRACE 360 <- [1,3,null,{}]
22:02:29 INFO - 1499835749658 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
22:02:29 INFO - 1499835749659 Marionette TRACE 360 <- [1,4,null,{}]
22:02:29 INFO - 1499835749661 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}]
22:02:29 INFO - TEST-START: test_click.py TestClick.test_container_element1499835749664 Marionette TRACE 360 <- [1,5,null,{"value":null}]
22:02:29 INFO - 1499835749665 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
22:02:29 INFO - 1499835749666 Marionette TRACE 360 <- [1,6,null,{}]
22:02:29 INFO - 1499835749668 Marionette DEBUG Closed connection 360
22:02:29 INFO - 1499835749669 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49856
22:02:29 INFO - 1499835749670 Marionette DEBUG Closed connection 361
22:02:29 INFO - 1499835749670 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49857
22:02:29 INFO - 1499835749671 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:29 INFO - 1499835749672 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:29 INFO - 1499835749675 Marionette DEBUG Register listener.js for window 4294967297
22:02:29 INFO - 1499835749678 Marionette TRACE 362 <- [1,1,null,{"sessionId":"691ca267-9696-4e3a-b724-d0882c9898fa","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:29 INFO - 1499835749679 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
22:02:29 INFO - 1499835749681 Marionette TRACE 362 <- [1,2,null,{}]
22:02:29 INFO - 1499835749683 Marionette DEBUG Closed connection 362
22:02:29 INFO - 1499835749683 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49858
22:02:29 INFO - 1499835749684 Marionette DEBUG Closed connection 363
22:02:29 INFO - 1499835749685 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49859
22:02:29 INFO - 1499835749686 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:02:29 INFO - 1499835749687 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:29 INFO - 1499835749690 Marionette DEBUG Register listener.js for window 4294967297
22:02:29 INFO - 1499835749693 Marionette TRACE 364 <- [1,1,null,{"sessionId":"12500a3c-71e6-4ddc-a931-30eb403113c9","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:29 INFO - 1499835749694 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"}]
22:02:29 INFO - 1499835749697 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49396/test.html"
22:02:29 INFO - 1499835749701 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49396/test.html"
22:02:29 INFO - 1499835749701 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49396/test.html"
22:02:29 INFO - 1499835749710 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"
22:02:29 INFO - 1499835749716 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"
22:02:29 INFO - 1499835749726 Marionette TRACE 364 <- [1,2,null,{}]
22:02:29 INFO - 1499835749737 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:02:29 INFO - 1499835749740 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f7c8a3ec-6242-4717-bed6-35473a21d60d","ELEMENT":"f7c8a3ec-6242-4717-bed6-35473a21d60d"}}]
22:02:29 INFO - 1499835749742 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"f7c8a3ec-6242-4717-bed6-35473a21d60d"}]
22:02:29 INFO - 1499835749987 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:02:29 INFO - 1499835749988 Marionette TRACE 364 <- [1,4,null,{}]
22:02:29 INFO - 1499835749989 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"f7c8a3ec-6242-4717-bed6-35473a21d60d","name":"selected"}]
22:02:30 INFO - 1499835749994 Marionette TRACE 364 <- [1,5,null,{"value":true}]
22:02:30 INFO - 1499835749996 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}]
22:02:30 INFO - TEST-END: test_click.py TestClick.test_container_element1499835749999 Marionette TRACE 364 <- [1,6,null,{"value":null}]
22:02:30 INFO - 1499835750000 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
22:02:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 361ms
22:02:30 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
22:02:30 INFO - 1499835750002 Marionette TRACE 364 <- [1,7,null,{}]
22:02:30 INFO - 1499835750004 Marionette DEBUG Closed connection 364
22:02:30 INFO - 1499835750005 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49860
22:02:30 INFO - 1499835750005 Marionette DEBUG Closed connection 365
22:02:30 INFO - 1499835750006 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49861
22:02:30 INFO - 1499835750007 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:30 INFO - 1499835750008 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750011 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750014 Marionette TRACE 366 <- [1,1,null,{"sessionId":"c11e6b11-606d-479f-b99c-6c840078a202","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750016 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
22:02:30 INFO - 1499835750016 Marionette TRACE 366 <- [1,2,null,{}]
22:02:30 INFO - 1499835750017 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:02:30 INFO - 1499835750018 Marionette TRACE 366 <- [1,3,null,{}]
22:02:30 INFO - 1499835750019 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
22:02:30 INFO - 1499835750019 Marionette TRACE 366 <- [1,4,null,{}]
22:02:30 INFO - 1499835750021 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}]
22:02:30 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499835750024 Marionette TRACE 366 <- [1,5,null,{"value":null}]
22:02:30 INFO - 1499835750025 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
22:02:30 INFO - 1499835750026 Marionette TRACE 366 <- [1,6,null,{}]
22:02:30 INFO - 1499835750028 Marionette DEBUG Closed connection 366
22:02:30 INFO - 1499835750029 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49862
22:02:30 INFO - 1499835750030 Marionette DEBUG Closed connection 367
22:02:30 INFO - 1499835750030 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49863
22:02:30 INFO - 1499835750032 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:30 INFO - 1499835750032 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750035 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750038 Marionette TRACE 368 <- [1,1,null,{"sessionId":"33be6d18-70d9-4caf-82c5-66ed1c0dc9ea","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750039 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
22:02:30 INFO - 1499835750041 Marionette TRACE 368 <- [1,2,null,{}]
22:02:30 INFO - 1499835750043 Marionette DEBUG Closed connection 368
22:02:30 INFO - 1499835750043 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49864
22:02:30 INFO - 1499835750044 Marionette DEBUG Closed connection 369
22:02:30 INFO - 1499835750045 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49865
22:02:30 INFO - 1499835750046 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:02:30 INFO - 1499835750047 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750050 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750054 Marionette TRACE 370 <- [1,1,null,{"sessionId":"a0346b70-c1ed-459b-850b-963fb143ac99","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750055 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"}]
22:02:30 INFO - 1499835750057 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"
22:02:30 INFO - 1499835750059 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"
22:02:30 INFO - 1499835750060 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"
22:02:30 INFO - 1499835750067 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"
22:02:30 INFO - 1499835750070 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"
22:02:30 INFO - 1499835750076 Marionette TRACE 370 <- [1,2,null,{}]
22:02:30 INFO - 1499835750088 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
22:02:30 INFO - 1499835750091 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"af419a45-7c6b-456d-befb-90f91479fcfb","ELEMENT":"af419a45-7c6b-456d-befb-90f91479fcfb"}}]
22:02:30 INFO - 1499835750092 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"af419a45-7c6b-456d-befb-90f91479fcfb"}]
22:02:30 INFO - 1499835750346 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:02:30 INFO - 1499835750347 Marionette TRACE 370 <- [1,4,null,{}]
22:02:30 INFO - 1499835750348 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"af419a45-7c6b-456d-befb-90f91479fcfb","name":"selected"}]
22:02:30 INFO - 1499835750353 Marionette TRACE 370 <- [1,5,null,{"value":true}]
22:02:30 INFO - 1499835750355 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}]
22:02:30 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499835750358 Marionette TRACE 370 <- [1,6,null,{"value":null}]
22:02:30 INFO - 1499835750359 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
22:02:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 359ms
22:02:30 INFO - TEST-START | test_click.py TestClick.test_css_transforms
22:02:30 INFO - 1499835750361 Marionette TRACE 370 <- [1,7,null,{}]
22:02:30 INFO - 1499835750363 Marionette DEBUG Closed connection 370
22:02:30 INFO - 1499835750364 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49866
22:02:30 INFO - 1499835750365 Marionette DEBUG Closed connection 371
22:02:30 INFO - 1499835750366 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49867
22:02:30 INFO - 1499835750367 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:30 INFO - 1499835750367 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750370 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750382 Marionette TRACE 372 <- [1,1,null,{"sessionId":"efbe0bd1-e046-4b55-b5f2-f9e3980d77e7","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750384 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
22:02:30 INFO - 1499835750384 Marionette TRACE 372 <- [1,2,null,{}]
22:02:30 INFO - 1499835750385 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:02:30 INFO - 1499835750386 Marionette TRACE 372 <- [1,3,null,{}]
22:02:30 INFO - 1499835750387 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
22:02:30 INFO - 1499835750387 Marionette TRACE 372 <- [1,4,null,{}]
22:02:30 INFO - 1499835750389 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}]
22:02:30 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499835750393 Marionette TRACE 372 <- [1,5,null,{"value":null}]
22:02:30 INFO - 1499835750394 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
22:02:30 INFO - 1499835750395 Marionette TRACE 372 <- [1,6,null,{}]
22:02:30 INFO - 1499835750398 Marionette DEBUG Closed connection 372
22:02:30 INFO - 1499835750399 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49868
22:02:30 INFO - 1499835750400 Marionette DEBUG Closed connection 373
22:02:30 INFO - 1499835750401 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49869
22:02:30 INFO - 1499835750402 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:30 INFO - 1499835750402 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750405 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750409 Marionette TRACE 374 <- [1,1,null,{"sessionId":"94d8288d-dfd3-4bbe-89e1-7832ab28292d","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750410 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
22:02:30 INFO - 1499835750411 Marionette TRACE 374 <- [1,2,null,{}]
22:02:30 INFO - 1499835750413 Marionette DEBUG Closed connection 374
22:02:30 INFO - 1499835750414 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49870
22:02:30 INFO - 1499835750415 Marionette DEBUG Closed connection 375
22:02:30 INFO - 1499835750415 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49871
22:02:30 INFO - 1499835750417 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:02:30 INFO - 1499835750417 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750420 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750423 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6725cb32-e5f2-41f2-87d0-a9dc1319dbd0","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750425 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"}]
22:02:30 INFO - 1499835750427 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"
22:02:30 INFO - 1499835750429 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"
22:02:30 INFO - 1499835750430 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"
22:02:30 INFO - 1499835750437 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"
22:02:30 INFO - 1499835750439 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"
22:02:30 INFO - 1499835750443 Marionette TRACE 376 <- [1,2,null,{}]
22:02:30 INFO - 1499835750455 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
22:02:30 INFO - 1499835750458 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b9a8c86e-46a2-4b8e-ba35-4ef7c063f535","ELEMENT":"b9a8c86e-46a2-4b8e-ba35-4ef7c063f535"}}]
22:02:30 INFO - 1499835750459 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"b9a8c86e-46a2-4b8e-ba35-4ef7c063f535"}]
22:02:30 INFO - 1499835750702 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:02:30 INFO - 1499835750703 Marionette TRACE 376 <- [1,4,null,{}]
22:02:30 INFO - 1499835750705 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}]
22:02:30 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499835750708 Marionette TRACE 376 <- [1,5,null,{"value":null}]
22:02:30 INFO - 1499835750709 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
22:02:30 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 350ms
22:02:30 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
22:02:30 INFO - 1499835750711 Marionette TRACE 376 <- [1,6,null,{}]
22:02:30 INFO - 1499835750713 Marionette DEBUG Closed connection 376
22:02:30 INFO - 1499835750714 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49872
22:02:30 INFO - 1499835750715 Marionette DEBUG Closed connection 377
22:02:30 INFO - 1499835750715 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49873
22:02:30 INFO - 1499835750716 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:30 INFO - 1499835750717 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750720 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750724 Marionette TRACE 378 <- [1,1,null,{"sessionId":"0a81d8a8-f0d9-4378-85c8-6044542d2cd1","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750725 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
22:02:30 INFO - 1499835750725 Marionette TRACE 378 <- [1,2,null,{}]
22:02:30 INFO - 1499835750726 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:02:30 INFO - 1499835750727 Marionette TRACE 378 <- [1,3,null,{}]
22:02:30 INFO - 1499835750728 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
22:02:30 INFO - 1499835750728 Marionette TRACE 378 <- [1,4,null,{}]
22:02:30 INFO - 1499835750730 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}]
22:02:30 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499835750733 Marionette TRACE 378 <- [1,5,null,{"value":null}]
22:02:30 INFO - 1499835750734 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
22:02:30 INFO - 1499835750736 Marionette TRACE 378 <- [1,6,null,{}]
22:02:30 INFO - 1499835750737 Marionette DEBUG Closed connection 378
22:02:30 INFO - 1499835750738 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49874
22:02:30 INFO - 1499835750739 Marionette DEBUG Closed connection 379
22:02:30 INFO - 1499835750740 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49875
22:02:30 INFO - 1499835750741 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:30 INFO - 1499835750741 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750744 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750747 Marionette TRACE 380 <- [1,1,null,{"sessionId":"5c955ccb-6acb-4112-8431-dab28446d7d5","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750749 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
22:02:30 INFO - 1499835750750 Marionette TRACE 380 <- [1,2,null,{}]
22:02:30 INFO - 1499835750752 Marionette DEBUG Closed connection 380
22:02:30 INFO - 1499835750753 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49876
22:02:30 INFO - 1499835750753 Marionette DEBUG Closed connection 381
22:02:30 INFO - 1499835750754 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49877
22:02:30 INFO - 1499835750755 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:02:30 INFO - 1499835750756 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:30 INFO - 1499835750759 Marionette DEBUG Register listener.js for window 4294967297
22:02:30 INFO - 1499835750762 Marionette TRACE 382 <- [1,1,null,{"sessionId":"45ebf658-88d7-4b2f-a3b7-cf51ce0c6eac","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:30 INFO - 1499835750763 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"}]
22:02:30 INFO - 1499835750765 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"
22:02:30 INFO - 1499835750768 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"
22:02:30 INFO - 1499835750768 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"
22:02:30 INFO - 1499835750775 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"
22:02:30 INFO - 1499835750778 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"
22:02:30 INFO - 1499835750788 Marionette TRACE 382 <- [1,2,null,{}]
22:02:30 INFO - 1499835750792 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
22:02:30 INFO - 1499835750795 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f984cad-746d-40f1-adb0-60aab4441764","ELEMENT":"7f984cad-746d-40f1-adb0-60aab4441764"}}]
22:02:30 INFO - 1499835750801 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"7f984cad-746d-40f1-adb0-60aab4441764"}]
22:02:31 INFO - 1499835751051 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:02:31 INFO - 1499835751052 Marionette TRACE 382 <- [1,4,null,{}]
22:02:31 INFO - 1499835751054 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}]
22:02:31 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499835751057 Marionette TRACE 382 <- [1,5,null,{"value":null}]
22:02:31 INFO - 1499835751058 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
22:02:31 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 348ms
22:02:31 INFO - TEST-START | test_click.py TestClick.test_input_file
22:02:31 INFO - 1499835751060 Marionette TRACE 382 <- [1,6,null,{}]
22:02:31 INFO - 1499835751061 Marionette DEBUG Closed connection 382
22:02:31 INFO - 1499835751062 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49878
22:02:31 INFO - 1499835751063 Marionette DEBUG Closed connection 383
22:02:31 INFO - 1499835751064 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49879
22:02:31 INFO - 1499835751065 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:31 INFO - 1499835751066 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751069 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751072 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b6c76791-af93-4ef9-8b1b-3a7d37f1a4cf","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751073 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
22:02:31 INFO - 1499835751074 Marionette TRACE 384 <- [1,2,null,{}]
22:02:31 INFO - 1499835751075 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:02:31 INFO - 1499835751075 Marionette TRACE 384 <- [1,3,null,{}]
22:02:31 INFO - 1499835751076 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
22:02:31 INFO - 1499835751077 Marionette TRACE 384 <- [1,4,null,{}]
22:02:31 INFO - 1499835751079 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}]
22:02:31 INFO - TEST-START: test_click.py TestClick.test_input_file1499835751082 Marionette TRACE 384 <- [1,5,null,{"value":null}]
22:02:31 INFO - 1499835751083 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
22:02:31 INFO - 1499835751084 Marionette TRACE 384 <- [1,6,null,{}]
22:02:31 INFO - 1499835751086 Marionette DEBUG Closed connection 384
22:02:31 INFO - 1499835751087 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49880
22:02:31 INFO - 1499835751088 Marionette DEBUG Closed connection 385
22:02:31 INFO - 1499835751088 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49881
22:02:31 INFO - 1499835751089 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:31 INFO - 1499835751090 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751093 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751096 Marionette TRACE 386 <- [1,1,null,{"sessionId":"891cf526-b701-4444-9092-3552bbacdf45","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751097 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
22:02:31 INFO - 1499835751099 Marionette TRACE 386 <- [1,2,null,{}]
22:02:31 INFO - 1499835751101 Marionette DEBUG Closed connection 386
22:02:31 INFO - 1499835751101 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49882
22:02:31 INFO - 1499835751102 Marionette DEBUG Closed connection 387
22:02:31 INFO - 1499835751103 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49883
22:02:31 INFO - 1499835751104 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:02:31 INFO - 1499835751105 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751108 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751111 Marionette TRACE 388 <- [1,1,null,{"sessionId":"d6e46bd6-27bc-4a17-8623-b590c5a61ba3","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751112 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
22:02:31 INFO - 1499835751114 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"
22:02:31 INFO - 1499835751116 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"
22:02:31 INFO - 1499835751117 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"
22:02:31 INFO - 1499835751124 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:02:31 INFO - 1499835751129 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:02:31 INFO - 1499835751143 Marionette TRACE 388 <- [1,2,null,{}]
22:02:31 INFO - 1499835751152 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
22:02:31 INFO - 1499835751155 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fffd501c-6426-407d-87e8-9b5540263bc5","ELEMENT":"fffd501c-6426-407d-87e8-9b5540263bc5"}}]
22:02:31 INFO - 1499835751156 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"fffd501c-6426-407d-87e8-9b5540263bc5"}]
22:02:31 INFO - 1499835751190 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]
22:02:31 INFO - 1499835751192 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}]
22:02:31 INFO - TEST-END: test_click.py TestClick.test_input_file1499835751196 Marionette TRACE 388 <- [1,5,null,{"value":null}]
22:02:31 INFO - 1499835751197 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
22:02:31 INFO - 1499835751198 Marionette TRACE 388 <- [1,6,null,{}]
22:02:31 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 138ms
22:02:31 INFO - TEST-START | test_click.py TestClick.test_obscured_element
22:02:31 INFO - 1499835751200 Marionette DEBUG Closed connection 388
22:02:31 INFO - 1499835751201 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49884
22:02:31 INFO - 1499835751202 Marionette DEBUG Closed connection 389
22:02:31 INFO - 1499835751202 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49885
22:02:31 INFO - 1499835751204 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:31 INFO - 1499835751204 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751207 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751211 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c3fdf8f1-166d-435b-90c7-77830aeca354","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751212 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
22:02:31 INFO - 1499835751212 Marionette TRACE 390 <- [1,2,null,{}]
22:02:31 INFO - 1499835751213 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:02:31 INFO - 1499835751214 Marionette TRACE 390 <- [1,3,null,{}]
22:02:31 INFO - 1499835751215 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
22:02:31 INFO - 1499835751216 Marionette TRACE 390 <- [1,4,null,{}]
22:02:31 INFO - 1499835751217 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}]
22:02:31 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499835751220 Marionette TRACE 390 <- [1,5,null,{"value":null}]
22:02:31 INFO - 1499835751221 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
22:02:31 INFO - 1499835751223 Marionette TRACE 390 <- [1,6,null,{}]
22:02:31 INFO - 1499835751224 Marionette DEBUG Closed connection 390
22:02:31 INFO - 1499835751225 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49886
22:02:31 INFO - 1499835751226 Marionette DEBUG Closed connection 391
22:02:31 INFO - 1499835751227 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49887
22:02:31 INFO - 1499835751228 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:31 INFO - 1499835751229 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751231 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751235 Marionette TRACE 392 <- [1,1,null,{"sessionId":"6b25c1c8-0a23-4f39-9756-1d1fd7323c64","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751237 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
22:02:31 INFO - 1499835751238 Marionette TRACE 392 <- [1,2,null,{}]
22:02:31 INFO - 1499835751240 Marionette DEBUG Closed connection 392
22:02:31 INFO - 1499835751240 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49888
22:02:31 INFO - 1499835751241 Marionette DEBUG Closed connection 393
22:02:31 INFO - 1499835751242 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49889
22:02:31 INFO - 1499835751243 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:02:31 INFO - 1499835751244 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751247 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751250 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3e85cbca-5ced-4181-940f-dcd4836fe5ff","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751251 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"}]
22:02:31 INFO - 1499835751253 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:02:31 INFO - 1499835751255 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:02:31 INFO - 1499835751256 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
22:02:31 INFO - 1499835751265 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"
22:02:31 INFO - 1499835751267 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"
22:02:31 INFO - 1499835751275 Marionette TRACE 394 <- [1,2,null,{}]
22:02:31 INFO - 1499835751284 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
22:02:31 INFO - 1499835751289 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9533da0f-cf82-48aa-bbd4-16d377dcbf13","ELEMENT":"9533da0f-cf82-48aa-bbd4-16d377dcbf13"}}]
22:02:31 INFO - 1499835751290 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
22:02:31 INFO - 1499835751293 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"811279ef-26bd-4f29-a211-11de6d5ee630","ELEMENT":"811279ef-26bd-4f29-a211-11de6d5ee630"}}]
22:02:31 INFO - 1499835751294 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"9533da0f-cf82-48aa-bbd4-16d377dcbf13"}]
22:02:31 INFO - 1499835751535 Marionette DEBUG Canceled page load listener because no navigation has been detected
22:02:31 INFO - 1499835751536 Marionette TRACE 394 <- [1,5,null,{}]
22:02:31 INFO - 1499835751537 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"811279ef-26bd-4f29-a211-11de6d5ee630"}]
22:02:31 INFO - 1499835751556 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
22:02:31 INFO - 1499835751558 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
22:02:31 INFO - 1499835751561 Marionette TRACE 394 <- [1,7,null,{"value":false}]
22:02:31 INFO - 1499835751563 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}]
22:02:31 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499835751566 Marionette TRACE 394 <- [1,8,null,{"value":null}]
22:02:31 INFO - 1499835751567 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
22:02:31 INFO - 1499835751568 Marionette TRACE 394 <- [1,9,null,{}]
22:02:31 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 370ms
22:02:31 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
22:02:31 INFO - 1499835751570 Marionette DEBUG Closed connection 394
22:02:31 INFO - 1499835751571 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49890
22:02:31 INFO - 1499835751572 Marionette DEBUG Closed connection 395
22:02:31 INFO - 1499835751572 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49891
22:02:31 INFO - 1499835751573 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:31 INFO - 1499835751574 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751577 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751581 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a90577e9-ae54-4962-a50b-44760e1a65ba","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751582 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
22:02:31 INFO - 1499835751583 Marionette TRACE 396 <- [1,2,null,{}]
22:02:31 INFO - 1499835751583 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
22:02:31 INFO - 1499835751584 Marionette TRACE 396 <- [1,3,null,{}]
22:02:31 INFO - 1499835751585 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
22:02:31 INFO - 1499835751586 Marionette TRACE 396 <- [1,4,null,{}]
22:02:31 INFO - 1499835751587 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}]
22:02:31 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499835751590 Marionette TRACE 396 <- [1,5,null,{"value":null}]
22:02:31 INFO - 1499835751591 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
22:02:31 INFO - 1499835751593 Marionette TRACE 396 <- [1,6,null,{}]
22:02:31 INFO - 1499835751595 Marionette DEBUG Closed connection 396
22:02:31 INFO - 1499835751595 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49892
22:02:31 INFO - 1499835751596 Marionette DEBUG Closed connection 397
22:02:31 INFO - 1499835751597 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49893
22:02:31 INFO - 1499835751598 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
22:02:31 INFO - 1499835751599 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751602 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751605 Marionette TRACE 398 <- [1,1,null,{"sessionId":"518c7ab7-52dd-4d74-b25a-480856c6373e","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751606 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
22:02:31 INFO - 1499835751608 Marionette TRACE 398 <- [1,2,null,{}]
22:02:31 INFO - 1499835751610 Marionette DEBUG Closed connection 398
22:02:31 INFO - 1499835751610 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49894
22:02:31 INFO - 1499835751611 Marionette DEBUG Closed connection 399
22:02:31 INFO - 1499835751612 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49895
22:02:31 INFO - 1499835751614 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
22:02:31 INFO - 1499835751614 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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}
22:02:31 INFO - 1499835751617 Marionette DEBUG Register listener.js for window 4294967297
22:02:31 INFO - 1499835751620 Marionette TRACE 400 <- [1,1,null,{"sessionId":"e6ec158c-2859-42e3-a557-ef22ab8407c1","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":928,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpla43vj.mozrunner","moz:accessibilityChecks":false}}]
22:02:31 INFO - 1499835751622 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"}]
22:02:31 INFO - 1499835751624 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"
22:02:31 INFO - 1499835751626 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"
22:02:31 INFO - 1499835751626 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"
22:02:31 INFO - 1499835751635 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"
22:02:31 INFO - 1499835751638 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"
22:02:31 INFO - 1499835751644 Marionette TRACE 400 <- [1,2,null,{}]
22:02:31 INFO - 1499835751656 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
22:02:31 INFO - 1499835751659 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e7fe4f3-d3e0-45dd-bf46-8b4aa85c8566","ELEMENT":"6e7fe4f3-d3e0-45dd-bf46-8b4aa85c8566"}}]
22:02:31 INFO - 1499835751660 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"6e7fe4f3-d3e0-45dd-bf46-8b4aa85c8566"}]
22:02:31 INFO - 1499835751684 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
22:02:31 INFO - 1499835751686 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"6e7fe4f3-d3e0-45dd-bf46-8b4aa85c8566"}]
22:02:31 INFO - 1499835751704 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element