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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
07:12:34 INFO - 1499695954453 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}]
07:12:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499695954455 Marionette TRACE 352 <- [1,5,null,{"value":null}]
07:12:34 INFO - 1499695954456 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
07:12:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 120ms
07:12:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
07:12:34 INFO - 1499695954457 Marionette TRACE 352 <- [1,6,null,{}]
07:12:34 INFO - 1499695954458 Marionette DEBUG Closed connection 352
07:12:34 INFO - 1499695954460 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49756
07:12:34 INFO - 1499695954460 Marionette DEBUG Closed connection 353
07:12:34 INFO - 1499695954461 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49757
07:12:34 INFO - 1499695954462 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:34 INFO - 1499695954462 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:34 INFO - 1499695954464 Marionette DEBUG Register listener.js for window 12
07:12:34 INFO - 1499695954466 Marionette TRACE 354 <- [1,1,null,{"sessionId":"529255aa-a9ad-4301-aad9-f3c588dde793","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:34 INFO - 1499695954468 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
07:12:34 INFO - 1499695954468 Marionette TRACE 354 <- [1,2,null,{}]
07:12:34 INFO - 1499695954469 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:12:34 INFO - 1499695954470 Marionette TRACE 354 <- [1,3,null,{}]
07:12:34 INFO - 1499695954471 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
07:12:34 INFO - 1499695954471 Marionette TRACE 354 <- [1,4,null,{}]
07:12:34 INFO - 1499695954472 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}]
07:12:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499695954474 Marionette TRACE 354 <- [1,5,null,{"value":null}]
07:12:34 INFO - 1499695954475 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
07:12:34 INFO - 1499695954476 Marionette TRACE 354 <- [1,6,null,{}]
07:12:34 INFO - 1499695954478 Marionette DEBUG Closed connection 354
07:12:34 INFO - 1499695954478 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49758
07:12:34 INFO - 1499695954479 Marionette DEBUG Closed connection 355
07:12:34 INFO - 1499695954480 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49759
07:12:34 INFO - 1499695954480 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:34 INFO - 1499695954488 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:34 INFO - 1499695954490 Marionette DEBUG Register listener.js for window 12
07:12:34 INFO - 1499695954493 Marionette TRACE 356 <- [1,1,null,{"sessionId":"3a5f1663-42ea-480e-b410-57324f3c6270","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:34 INFO - 1499695954495 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
07:12:34 INFO - 1499695954496 Marionette TRACE 356 <- [1,2,null,{}]
07:12:34 INFO - 1499695954498 Marionette DEBUG Closed connection 356
07:12:34 INFO - 1499695954498 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49760
07:12:34 INFO - 1499695954499 Marionette DEBUG Closed connection 357
07:12:34 INFO - 1499695954499 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49761
07:12:34 INFO - 1499695954500 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:12:34 INFO - 1499695954501 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:34 INFO - 1499695954503 Marionette DEBUG Register listener.js for window 12
07:12:34 INFO - 1499695954506 Marionette TRACE 358 <- [1,1,null,{"sessionId":"7e5cff67-9ed1-4ec4-96d8-f6eada7390bb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:34 INFO - 1499695954507 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
07:12:34 INFO - 1499695954509 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
07:12:34 INFO - 1499695954516 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
07:12:34 INFO - 1499695954517 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
07:12:34 INFO - 1499695954527 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
07:12:34 INFO - 1499695954535 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
07:12:34 INFO - 1499695954539 Marionette TRACE 358 <- [1,2,null,{}]
07:12:34 INFO - 1499695954540 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
07:12:34 INFO - 1499695954542 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a1a5e161-8484-48ae-a308-1075e0285712","ELEMENT":"a1a5e161-8484-48ae-a308-1075e0285712"}}]
07:12:34 INFO - 1499695954543 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a1a5e161-8484-48ae-a308-1075e0285712"}]
07:12:34 INFO - 1499695954581 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
07:12:34 INFO - 1499695954589 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
07:12:34 INFO - 1499695954589 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
07:12:34 INFO - 1499695954604 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
07:12:34 INFO - 1499695954607 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
07:12:34 INFO - 1499695954610 Marionette TRACE 358 <- [1,4,null,{}]
07:12:34 INFO - 1499695954615 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
07:12:34 INFO - 1499695954618 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05dfbfb0-a897-4f6c-bef0-7e5801a33df2","ELEMENT":"05dfbfb0-a897-4f6c-bef0-7e5801a33df2"}}]
07:12:34 INFO - 1499695954620 Marionette TRACE 358 -> [0,6,"getTitle",{}]
07:12:34 INFO - 1499695954620 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
07:12:34 INFO - 1499695954621 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}]
07:12:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499695954623 Marionette TRACE 358 <- [1,7,null,{"value":null}]
07:12:34 INFO - 1499695954624 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
07:12:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 168ms
07:12:34 INFO - TEST-START | test_click.py TestClick.test_container_element
07:12:34 INFO - 1499695954625 Marionette TRACE 358 <- [1,8,null,{}]
07:12:34 INFO - 1499695954627 Marionette DEBUG Closed connection 358
07:12:34 INFO - 1499695954627 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49764
07:12:34 INFO - 1499695954628 Marionette DEBUG Closed connection 359
07:12:34 INFO - 1499695954629 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49765
07:12:34 INFO - 1499695954630 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:34 INFO - 1499695954631 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:34 INFO - 1499695954633 Marionette DEBUG Register listener.js for window 12
07:12:34 INFO - 1499695954635 Marionette TRACE 360 <- [1,1,null,{"sessionId":"f5ac28e4-ed40-4153-8152-486d0dfb4286","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:34 INFO - 1499695954637 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
07:12:34 INFO - 1499695954638 Marionette TRACE 360 <- [1,2,null,{}]
07:12:34 INFO - 1499695954640 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:12:34 INFO - 1499695954640 Marionette TRACE 360 <- [1,3,null,{}]
07:12:34 INFO - 1499695954641 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
07:12:34 INFO - 1499695954641 Marionette TRACE 360 <- [1,4,null,{}]
07:12:34 INFO - 1499695954642 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}]
07:12:34 INFO - TEST-START: test_click.py TestClick.test_container_element1499695954645 Marionette TRACE 360 <- [1,5,null,{"value":null}]
07:12:34 INFO - 1499695954659 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
07:12:34 INFO - 1499695954661 Marionette TRACE 360 <- [1,6,null,{}]
07:12:34 INFO - 1499695954663 Marionette DEBUG Closed connection 360
07:12:34 INFO - 1499695954663 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49766
07:12:34 INFO - 1499695954664 Marionette DEBUG Closed connection 361
07:12:34 INFO - 1499695954664 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49767
07:12:34 INFO - 1499695954665 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:34 INFO - 1499695954666 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:34 INFO - 1499695954668 Marionette DEBUG Register listener.js for window 12
07:12:34 INFO - 1499695954670 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ebfcedca-4e84-495d-b2ba-b30afe60ff3c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:34 INFO - 1499695954672 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
07:12:34 INFO - 1499695954673 Marionette TRACE 362 <- [1,2,null,{}]
07:12:34 INFO - 1499695954675 Marionette DEBUG Closed connection 362
07:12:34 INFO - 1499695954675 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49768
07:12:34 INFO - 1499695954676 Marionette DEBUG Closed connection 363
07:12:34 INFO - 1499695954676 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49769
07:12:34 INFO - 1499695954677 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:12:34 INFO - 1499695954678 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:34 INFO - 1499695954679 Marionette DEBUG Register listener.js for window 12
07:12:34 INFO - 1499695954682 Marionette TRACE 364 <- [1,1,null,{"sessionId":"5db78b2c-0133-477e-8ffa-a3f6723c50d7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:34 INFO - 1499695954684 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"}]
07:12:34 INFO - 1499695954685 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
07:12:34 INFO - 1499695954687 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
07:12:34 INFO - 1499695954687 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
07:12:34 INFO - 1499695954705 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"
07:12:34 INFO - 1499695954709 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"
07:12:34 INFO - 1499695954712 Marionette TRACE 364 <- [1,2,null,{}]
07:12:34 INFO - 1499695954725 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:12:34 INFO - 1499695954727 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e5337578-a46d-4d32-afb1-f8811f56ec17","ELEMENT":"e5337578-a46d-4d32-afb1-f8811f56ec17"}}]
07:12:34 INFO - 1499695954736 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e5337578-a46d-4d32-afb1-f8811f56ec17"}]
07:12:34 INFO - 1499695954978 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:12:34 INFO - 1499695954979 Marionette TRACE 364 <- [1,4,null,{}]
07:12:34 INFO - 1499695954980 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e5337578-a46d-4d32-afb1-f8811f56ec17","name":"selected"}]
07:12:34 INFO - 1499695954984 Marionette TRACE 364 <- [1,5,null,{"value":true}]
07:12:34 INFO - 1499695954985 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}]
07:12:34 INFO - TEST-END: test_click.py TestClick.test_container_element1499695954987 Marionette TRACE 364 <- [1,6,null,{"value":null}]
07:12:34 INFO - 1499695954988 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
07:12:34 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 364ms
07:12:34 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
07:12:34 INFO - 1499695954989 Marionette TRACE 364 <- [1,7,null,{}]
07:12:34 INFO - 1499695954991 Marionette DEBUG Closed connection 364
07:12:34 INFO - 1499695954992 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49770
07:12:34 INFO - 1499695954992 Marionette DEBUG Closed connection 365
07:12:34 INFO - 1499695954993 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49771
07:12:34 INFO - 1499695954994 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:34 INFO - 1499695954994 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:34 INFO - 1499695954996 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695954999 Marionette TRACE 366 <- [1,1,null,{"sessionId":"fcd676fe-2d0e-4b29-8f27-3ffe045554d0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955000 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
07:12:35 INFO - 1499695955001 Marionette TRACE 366 <- [1,2,null,{}]
07:12:35 INFO - 1499695955001 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:12:35 INFO - 1499695955002 Marionette TRACE 366 <- [1,3,null,{}]
07:12:35 INFO - 1499695955002 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
07:12:35 INFO - 1499695955003 Marionette TRACE 366 <- [1,4,null,{}]
07:12:35 INFO - 1499695955004 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}]
07:12:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499695955006 Marionette TRACE 366 <- [1,5,null,{"value":null}]
07:12:35 INFO - 1499695955007 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
07:12:35 INFO - 1499695955008 Marionette TRACE 366 <- [1,6,null,{}]
07:12:35 INFO - 1499695955010 Marionette DEBUG Closed connection 366
07:12:35 INFO - 1499695955010 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49772
07:12:35 INFO - 1499695955011 Marionette DEBUG Closed connection 367
07:12:35 INFO - 1499695955011 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49773
07:12:35 INFO - 1499695955012 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:35 INFO - 1499695955012 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955014 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955016 Marionette TRACE 368 <- [1,1,null,{"sessionId":"9587e232-13a3-4bfe-8d87-35688c59abef","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955018 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
07:12:35 INFO - 1499695955019 Marionette TRACE 368 <- [1,2,null,{}]
07:12:35 INFO - 1499695955021 Marionette DEBUG Closed connection 368
07:12:35 INFO - 1499695955021 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49774
07:12:35 INFO - 1499695955022 Marionette DEBUG Closed connection 369
07:12:35 INFO - 1499695955023 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49775
07:12:35 INFO - 1499695955024 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:12:35 INFO - 1499695955024 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955026 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955028 Marionette TRACE 370 <- [1,1,null,{"sessionId":"078a6fee-f18d-4dbb-a9f1-bdb2c0a23ba1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955030 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"}]
07:12:35 INFO - 1499695955031 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"
07:12:35 INFO - 1499695955032 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"
07:12:35 INFO - 1499695955033 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"
07:12:35 INFO - 1499695955042 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"
07:12:35 INFO - 1499695955050 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"
07:12:35 INFO - 1499695955053 Marionette TRACE 370 <- [1,2,null,{}]
07:12:35 INFO - 1499695955054 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:12:35 INFO - 1499695955056 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a915b9cb-db99-495b-859b-12934fe5d125","ELEMENT":"a915b9cb-db99-495b-859b-12934fe5d125"}}]
07:12:35 INFO - 1499695955057 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a915b9cb-db99-495b-859b-12934fe5d125"}]
07:12:35 INFO - 1499695955296 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:12:35 INFO - 1499695955297 Marionette TRACE 370 <- [1,4,null,{}]
07:12:35 INFO - 1499695955298 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a915b9cb-db99-495b-859b-12934fe5d125","name":"selected"}]
07:12:35 INFO - 1499695955302 Marionette TRACE 370 <- [1,5,null,{"value":true}]
07:12:35 INFO - 1499695955303 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}]
07:12:35 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499695955305 Marionette TRACE 370 <- [1,6,null,{"value":null}]
07:12:35 INFO - 1499695955306 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
07:12:35 INFO - 1499695955307 Marionette TRACE 370 <- [1,7,null,{}]
07:12:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 318ms
07:12:35 INFO - TEST-START | test_click.py TestClick.test_css_transforms
07:12:35 INFO - 1499695955309 Marionette DEBUG Closed connection 370
07:12:35 INFO - 1499695955310 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49776
07:12:35 INFO - 1499695955311 Marionette DEBUG Closed connection 371
07:12:35 INFO - 1499695955312 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49777
07:12:35 INFO - 1499695955312 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:35 INFO - 1499695955313 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955315 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955317 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ce1d403b-a753-4907-939f-b7caab594a06","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955319 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
07:12:35 INFO - 1499695955319 Marionette TRACE 372 <- [1,2,null,{}]
07:12:35 INFO - 1499695955320 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:12:35 INFO - 1499695955320 Marionette TRACE 372 <- [1,3,null,{}]
07:12:35 INFO - 1499695955321 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
07:12:35 INFO - 1499695955322 Marionette TRACE 372 <- [1,4,null,{}]
07:12:35 INFO - 1499695955323 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}]
07:12:35 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499695955325 Marionette TRACE 372 <- [1,5,null,{"value":null}]
07:12:35 INFO - 1499695955326 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
07:12:35 INFO - 1499695955327 Marionette TRACE 372 <- [1,6,null,{}]
07:12:35 INFO - 1499695955328 Marionette DEBUG Closed connection 372
07:12:35 INFO - 1499695955329 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49778
07:12:35 INFO - 1499695955330 Marionette DEBUG Closed connection 373
07:12:35 INFO - 1499695955330 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49779
07:12:35 INFO - 1499695955331 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:35 INFO - 1499695955331 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955333 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955342 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4a2bb99d-1f79-4a6c-92ad-f58ca49f4e2a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955344 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
07:12:35 INFO - 1499695955345 Marionette TRACE 374 <- [1,2,null,{}]
07:12:35 INFO - 1499695955347 Marionette DEBUG Closed connection 374
07:12:35 INFO - 1499695955347 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49780
07:12:35 INFO - 1499695955348 Marionette DEBUG Closed connection 375
07:12:35 INFO - 1499695955349 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49781
07:12:35 INFO - 1499695955349 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:12:35 INFO - 1499695955351 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955353 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955355 Marionette TRACE 376 <- [1,1,null,{"sessionId":"f09908c2-2716-421c-b0d3-e9cb15e6d9fc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955357 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"}]
07:12:35 INFO - 1499695955358 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"
07:12:35 INFO - 1499695955360 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"
07:12:35 INFO - 1499695955360 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"
07:12:35 INFO - 1499695955377 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"
07:12:35 INFO - 1499695955380 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"
07:12:35 INFO - 1499695955388 Marionette TRACE 376 <- [1,2,null,{}]
07:12:35 INFO - 1499695955390 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
07:12:35 INFO - 1499695955392 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c05a6d54-f985-4a43-b114-a693922a57c7","ELEMENT":"c05a6d54-f985-4a43-b114-a693922a57c7"}}]
07:12:35 INFO - 1499695955392 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"c05a6d54-f985-4a43-b114-a693922a57c7"}]
07:12:35 INFO - 1499695955627 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:12:35 INFO - 1499695955628 Marionette TRACE 376 <- [1,4,null,{}]
07:12:35 INFO - 1499695955630 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}]
07:12:35 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499695955631 Marionette TRACE 376 <- [1,5,null,{"value":null}]
07:12:35 INFO - 1499695955632 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
07:12:35 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 326ms
07:12:35 INFO - 1499695955633 Marionette TRACE 376 <- [1,6,null,{}]
07:12:35 INFO - 1499695955635 Marionette DEBUG Closed connection 376
07:12:35 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
07:12:35 INFO - 1499695955637 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49782
07:12:35 INFO - 1499695955638 Marionette DEBUG Closed connection 377
07:12:35 INFO - 1499695955638 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49783
07:12:35 INFO - 1499695955639 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:35 INFO - 1499695955639 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955641 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955644 Marionette TRACE 378 <- [1,1,null,{"sessionId":"051b1e8a-06f7-497f-b2c8-d5a5899acb1d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955646 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
07:12:35 INFO - 1499695955646 Marionette TRACE 378 <- [1,2,null,{}]
07:12:35 INFO - 1499695955647 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:12:35 INFO - 1499695955647 Marionette TRACE 378 <- [1,3,null,{}]
07:12:35 INFO - 1499695955648 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
07:12:35 INFO - 1499695955649 Marionette TRACE 378 <- [1,4,null,{}]
07:12:35 INFO - 1499695955650 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}]
07:12:35 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499695955652 Marionette TRACE 378 <- [1,5,null,{"value":null}]
07:12:35 INFO - 1499695955653 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
07:12:35 INFO - 1499695955654 Marionette TRACE 378 <- [1,6,null,{}]
07:12:35 INFO - 1499695955655 Marionette DEBUG Closed connection 378
07:12:35 INFO - 1499695955656 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49784
07:12:35 INFO - 1499695955657 Marionette DEBUG Closed connection 379
07:12:35 INFO - 1499695955658 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49785
07:12:35 INFO - 1499695955658 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:35 INFO - 1499695955659 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955661 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955663 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3301b490-a87c-4ac9-b413-3ef8f0256cb3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955665 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
07:12:35 INFO - 1499695955666 Marionette TRACE 380 <- [1,2,null,{}]
07:12:35 INFO - 1499695955667 Marionette DEBUG Closed connection 380
07:12:35 INFO - 1499695955668 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49786
07:12:35 INFO - 1499695955669 Marionette DEBUG Closed connection 381
07:12:35 INFO - 1499695955669 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49787
07:12:35 INFO - 1499695955670 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:12:35 INFO - 1499695955671 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955673 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955675 Marionette TRACE 382 <- [1,1,null,{"sessionId":"3a894535-0a3a-4055-8a54-f1f88432da05","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955677 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"}]
07:12:35 INFO - 1499695955678 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"
07:12:35 INFO - 1499695955679 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"
07:12:35 INFO - 1499695955680 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"
07:12:35 INFO - 1499695955688 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"
07:12:35 INFO - 1499695955692 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"
07:12:35 INFO - 1499695955694 Marionette TRACE 382 <- [1,2,null,{}]
07:12:35 INFO - 1499695955703 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
07:12:35 INFO - 1499695955704 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8db7aed0-f873-4443-87e6-7b18cd6e4081","ELEMENT":"8db7aed0-f873-4443-87e6-7b18cd6e4081"}}]
07:12:35 INFO - 1499695955706 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"8db7aed0-f873-4443-87e6-7b18cd6e4081"}]
07:12:35 INFO - 1499695955950 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:12:35 INFO - 1499695955950 Marionette TRACE 382 <- [1,4,null,{}]
07:12:35 INFO - 1499695955952 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}]
07:12:35 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499695955954 Marionette TRACE 382 <- [1,5,null,{"value":null}]
07:12:35 INFO - 1499695955955 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
07:12:35 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 322ms
07:12:35 INFO - TEST-START | test_click.py TestClick.test_input_file
07:12:35 INFO - 1499695955956 Marionette TRACE 382 <- [1,6,null,{}]
07:12:35 INFO - 1499695955958 Marionette DEBUG Closed connection 382
07:12:35 INFO - 1499695955959 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49788
07:12:35 INFO - 1499695955960 Marionette DEBUG Closed connection 383
07:12:35 INFO - 1499695955960 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49789
07:12:35 INFO - 1499695955961 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:35 INFO - 1499695955962 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955964 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955966 Marionette TRACE 384 <- [1,1,null,{"sessionId":"53fb5081-5df9-4075-873d-9ff1deb4988a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955969 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
07:12:35 INFO - 1499695955969 Marionette TRACE 384 <- [1,2,null,{}]
07:12:35 INFO - 1499695955970 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:12:35 INFO - 1499695955970 Marionette TRACE 384 <- [1,3,null,{}]
07:12:35 INFO - 1499695955971 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
07:12:35 INFO - 1499695955971 Marionette TRACE 384 <- [1,4,null,{}]
07:12:35 INFO - 1499695955973 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}]
07:12:35 INFO - TEST-START: test_click.py TestClick.test_input_file1499695955975 Marionette TRACE 384 <- [1,5,null,{"value":null}]
07:12:35 INFO - 1499695955975 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
07:12:35 INFO - 1499695955977 Marionette TRACE 384 <- [1,6,null,{}]
07:12:35 INFO - 1499695955978 Marionette DEBUG Closed connection 384
07:12:35 INFO - 1499695955979 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49790
07:12:35 INFO - 1499695955980 Marionette DEBUG Closed connection 385
07:12:35 INFO - 1499695955980 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49791
07:12:35 INFO - 1499695955981 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:35 INFO - 1499695955981 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955983 Marionette DEBUG Register listener.js for window 12
07:12:35 INFO - 1499695955986 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f22237cd-192e-4fc2-8310-874b8dab8e7c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:35 INFO - 1499695955987 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
07:12:35 INFO - 1499695955988 Marionette TRACE 386 <- [1,2,null,{}]
07:12:35 INFO - 1499695955990 Marionette DEBUG Closed connection 386
07:12:35 INFO - 1499695955991 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49792
07:12:35 INFO - 1499695955991 Marionette DEBUG Closed connection 387
07:12:35 INFO - 1499695955992 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49793
07:12:35 INFO - 1499695955993 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:12:35 INFO - 1499695955993 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:35 INFO - 1499695955995 Marionette DEBUG Register listener.js for window 12
07:12:36 INFO - 1499695955997 Marionette TRACE 388 <- [1,1,null,{"sessionId":"9be04c14-8f04-4769-bf54-70760ba52bef","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:36 INFO - 1499695955999 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
07:12:36 INFO - 1499695956000 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"
07:12:36 INFO - 1499695956002 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"
07:12:36 INFO - 1499695956002 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"
07:12:36 INFO - 1499695956018 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:12:36 INFO - 1499695956020 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:12:36 INFO - 1499695956023 Marionette TRACE 388 <- [1,2,null,{}]
07:12:36 INFO - 1499695956024 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
07:12:36 INFO - 1499695956026 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5189a38e-ddf7-498b-ab41-a6bc177262d6","ELEMENT":"5189a38e-ddf7-498b-ab41-a6bc177262d6"}}]
07:12:36 INFO - 1499695956027 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"5189a38e-ddf7-498b-ab41-a6bc177262d6"}]
07:12:36 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 107ms
07:12:36 INFO - TEST-START | test_click.py TestClick.test_obscured_element
07:12:36 INFO - 1499695956058 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
07:12:36 INFO - 1499695956060 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}]
07:12:36 INFO - TEST-END: test_click.py TestClick.test_input_file1499695956062 Marionette TRACE 388 <- [1,5,null,{"value":null}]
07:12:36 INFO - 1499695956063 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
07:12:36 INFO - 1499695956064 Marionette TRACE 388 <- [1,6,null,{}]
07:12:36 INFO - 1499695956066 Marionette DEBUG Closed connection 388
07:12:36 INFO - 1499695956067 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49794
07:12:36 INFO - 1499695956067 Marionette DEBUG Closed connection 389
07:12:36 INFO - 1499695956068 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49795
07:12:36 INFO - 1499695956069 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:36 INFO - 1499695956069 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:36 INFO - 1499695956071 Marionette DEBUG Register listener.js for window 12
07:12:36 INFO - 1499695956074 Marionette TRACE 390 <- [1,1,null,{"sessionId":"0d9a862a-3dde-48c1-9b33-61020c96bd38","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:36 INFO - 1499695956075 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
07:12:36 INFO - 1499695956076 Marionette TRACE 390 <- [1,2,null,{}]
07:12:36 INFO - 1499695956076 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:12:36 INFO - 1499695956077 Marionette TRACE 390 <- [1,3,null,{}]
07:12:36 INFO - 1499695956077 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
07:12:36 INFO - 1499695956078 Marionette TRACE 390 <- [1,4,null,{}]
07:12:36 INFO - 1499695956079 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}]
07:12:36 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499695956082 Marionette TRACE 390 <- [1,5,null,{"value":null}]
07:12:36 INFO - 1499695956082 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
07:12:36 INFO - 1499695956084 Marionette TRACE 390 <- [1,6,null,{}]
07:12:36 INFO - 1499695956086 Marionette DEBUG Closed connection 390
07:12:36 INFO - 1499695956086 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49796
07:12:36 INFO - 1499695956087 Marionette DEBUG Closed connection 391
07:12:36 INFO - 1499695956087 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49797
07:12:36 INFO - 1499695956088 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:36 INFO - 1499695956089 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:36 INFO - 1499695956091 Marionette DEBUG Register listener.js for window 12
07:12:36 INFO - 1499695956093 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a365ee16-e834-4845-9901-01e1c1895214","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:36 INFO - 1499695956095 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
07:12:36 INFO - 1499695956096 Marionette TRACE 392 <- [1,2,null,{}]
07:12:36 INFO - 1499695956098 Marionette DEBUG Closed connection 392
07:12:36 INFO - 1499695956099 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49798
07:12:36 INFO - 1499695956099 Marionette DEBUG Closed connection 393
07:12:36 INFO - 1499695956100 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49799
07:12:36 INFO - 1499695956100 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:12:36 INFO - 1499695956101 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:36 INFO - 1499695956103 Marionette DEBUG Register listener.js for window 12
07:12:36 INFO - 1499695956112 Marionette TRACE 394 <- [1,1,null,{"sessionId":"c19e1a7c-bc56-4674-b4b1-d47d3a229628","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:36 INFO - 1499695956114 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"}]
07:12:36 INFO - 1499695956115 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:12:36 INFO - 1499695956117 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:12:36 INFO - 1499695956118 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:12:36 INFO - 1499695956127 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"
07:12:36 INFO - 1499695956131 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"
07:12:36 INFO - 1499695956138 Marionette TRACE 394 <- [1,2,null,{}]
07:12:36 INFO - 1499695956139 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
07:12:36 INFO - 1499695956141 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"54b5a9b8-80bd-4665-aa56-27a1dd88c1fc","ELEMENT":"54b5a9b8-80bd-4665-aa56-27a1dd88c1fc"}}]
07:12:36 INFO - 1499695956142 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
07:12:36 INFO - 1499695956143 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c472421d-b970-407e-8034-1a5c67261836","ELEMENT":"c472421d-b970-407e-8034-1a5c67261836"}}]
07:12:36 INFO - 1499695956145 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"54b5a9b8-80bd-4665-aa56-27a1dd88c1fc"}]
07:12:36 INFO - 1499695956379 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:12:36 INFO - 1499695956380 Marionette TRACE 394 <- [1,5,null,{}]
07:12:36 INFO - 1499695956381 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c472421d-b970-407e-8034-1a5c67261836"}]
07:12:36 INFO - 1499695956394 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
07:12:36 INFO - 1499695956396 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
07:12:36 INFO - 1499695956398 Marionette TRACE 394 <- [1,7,null,{"value":false}]
07:12:36 INFO - 1499695956399 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}]
07:12:36 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499695956401 Marionette TRACE 394 <- [1,8,null,{"value":null}]
07:12:36 INFO - 1499695956402 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
07:12:36 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 339ms
07:12:36 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
07:12:36 INFO - 1499695956404 Marionette TRACE 394 <- [1,9,null,{}]
07:12:36 INFO - 1499695956405 Marionette DEBUG Closed connection 394
07:12:36 INFO - 1499695956406 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49800
07:12:36 INFO - 1499695956407 Marionette DEBUG Closed connection 395
07:12:36 INFO - 1499695956407 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49801
07:12:36 INFO - 1499695956408 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:36 INFO - 1499695956408 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:36 INFO - 1499695956411 Marionette DEBUG Register listener.js for window 12
07:12:36 INFO - 1499695956413 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8503f577-785e-4b60-a512-378c1dc89a37","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:36 INFO - 1499695956415 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
07:12:36 INFO - 1499695956415 Marionette TRACE 396 <- [1,2,null,{}]
07:12:36 INFO - 1499695956416 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:12:36 INFO - 1499695956416 Marionette TRACE 396 <- [1,3,null,{}]
07:12:36 INFO - 1499695956418 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
07:12:36 INFO - 1499695956418 Marionette TRACE 396 <- [1,4,null,{}]
07:12:36 INFO - 1499695956420 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}]
07:12:36 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499695956422 Marionette TRACE 396 <- [1,5,null,{"value":null}]
07:12:36 INFO - 1499695956422 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
07:12:36 INFO - 1499695956424 Marionette TRACE 396 <- [1,6,null,{}]
07:12:36 INFO - 1499695956425 Marionette DEBUG Closed connection 396
07:12:36 INFO - 1499695956426 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49802
07:12:36 INFO - 1499695956427 Marionette DEBUG Closed connection 397
07:12:36 INFO - 1499695956427 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49803
07:12:36 INFO - 1499695956428 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:12:36 INFO - 1499695956429 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:36 INFO - 1499695956431 Marionette DEBUG Register listener.js for window 12
07:12:36 INFO - 1499695956434 Marionette TRACE 398 <- [1,1,null,{"sessionId":"cad58990-ba31-4213-92b7-c848640b61e3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:36 INFO - 1499695956436 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
07:12:36 INFO - 1499695956437 Marionette TRACE 398 <- [1,2,null,{}]
07:12:36 INFO - 1499695956439 Marionette DEBUG Closed connection 398
07:12:36 INFO - 1499695956439 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49804
07:12:36 INFO - 1499695956440 Marionette DEBUG Closed connection 399
07:12:36 INFO - 1499695956440 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49805
07:12:36 INFO - 1499695956441 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:12:36 INFO - 1499695956442 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}
07:12:36 INFO - 1499695956445 Marionette DEBUG Register listener.js for window 12
07:12:36 INFO - 1499695956447 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a0788a33-bf54-4b6b-b81d-405c620f88af","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2776,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-098.001\\AppData\\Local\\Temp\\tmpqkg9eb.mozrunner","moz:accessibilityChecks":false}}]
07:12:36 INFO - 1499695956449 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"}]
07:12:36 INFO - 1499695956450 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"
07:12:36 INFO - 1499695956453 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"
07:12:36 INFO - 1499695956453 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"
07:12:36 INFO - 1499695956471 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"
07:12:36 INFO - 1499695956473 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"
07:12:36 INFO - 1499695956475 Marionette TRACE 400 <- [1,2,null,{}]
07:12:36 INFO - 1499695956476 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
07:12:36 INFO - 1499695956483 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1f76531d-966c-4b1a-9805-51e3580a00be","ELEMENT":"1f76531d-966c-4b1a-9805-51e3580a00be"}}]
07:12:36 INFO - 1499695956485 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1f76531d-966c-4b1a-9805-51e3580a00be"}]
07:12:36 INFO - 1499695956515 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
07:12:36 INFO - 1499695956516 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"1f76531d-966c-4b1a-9805-51e3580a00be"}]
07:12:36 INFO - 1499695956532 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element