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]
10:20:13 INFO - 1500657613486 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}]
10:20:13 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500657613489 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:20:13 INFO - 1500657613489 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:20:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 131ms
10:20:13 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:20:13 INFO - 1500657613491 Marionette TRACE 352 <- [1,6,null,{}]
10:20:13 INFO - 1500657613493 Marionette DEBUG Closed connection 352
10:20:13 INFO - 1500657613494 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49780
10:20:13 INFO - 1500657613494 Marionette DEBUG Closed connection 353
10:20:13 INFO - 1500657613495 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49781
10:20:13 INFO - 1500657613496 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:13 INFO - 1500657613498 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:13 INFO - 1500657613500 Marionette DEBUG Register listener.js for window 15
10:20:13 INFO - 1500657613503 Marionette TRACE 354 <- [1,1,null,{"sessionId":"35929ea1-9dea-46f2-8a2a-844174cafae0","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:13 INFO - 1500657613513 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:20:13 INFO - 1500657613513 Marionette TRACE 354 <- [1,2,null,{}]
10:20:13 INFO - 1500657613514 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:20:13 INFO - 1500657613515 Marionette TRACE 354 <- [1,3,null,{}]
10:20:13 INFO - 1500657613516 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:20:13 INFO - 1500657613516 Marionette TRACE 354 <- [1,4,null,{}]
10:20:13 INFO - 1500657613518 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}]
10:20:13 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500657613520 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:20:13 INFO - 1500657613521 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:20:13 INFO - 1500657613523 Marionette TRACE 354 <- [1,6,null,{}]
10:20:13 INFO - 1500657613524 Marionette DEBUG Closed connection 354
10:20:13 INFO - 1500657613525 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49782
10:20:13 INFO - 1500657613526 Marionette DEBUG Closed connection 355
10:20:13 INFO - 1500657613527 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49783
10:20:13 INFO - 1500657613528 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:13 INFO - 1500657613528 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:13 INFO - 1500657613530 Marionette DEBUG Register listener.js for window 15
10:20:13 INFO - 1500657613533 Marionette TRACE 356 <- [1,1,null,{"sessionId":"15a5ed9f-7955-4a3d-8498-0bbb79f25846","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:13 INFO - 1500657613535 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:20:13 INFO - 1500657613536 Marionette TRACE 356 <- [1,2,null,{}]
10:20:13 INFO - 1500657613538 Marionette DEBUG Closed connection 356
10:20:13 INFO - 1500657613538 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49784
10:20:13 INFO - 1500657613539 Marionette DEBUG Closed connection 357
10:20:13 INFO - 1500657613540 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49785
10:20:13 INFO - 1500657613541 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:20:13 INFO - 1500657613542 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:13 INFO - 1500657613544 Marionette DEBUG Register listener.js for window 15
10:20:13 INFO - 1500657613546 Marionette TRACE 358 <- [1,1,null,{"sessionId":"d6789d80-269f-46e5-a816-99bc339a126d","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:13 INFO - 1500657613548 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49346/clicks.html"}]
10:20:13 INFO - 1500657613550 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/hidden.html"
10:20:13 INFO - 1500657613555 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/hidden.html"
10:20:13 INFO - 1500657613555 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/hidden.html"
10:20:13 INFO - 1500657613572 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49346/clicks.html"
10:20:13 INFO - 1500657613576 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49346/clicks.html"
10:20:13 INFO - 1500657613580 Marionette TRACE 358 <- [1,2,null,{}]
10:20:13 INFO - 1500657613588 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:20:13 INFO - 1500657613590 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1454ef54-bd46-4acd-a2d4-ef610f387923","ELEMENT":"1454ef54-bd46-4acd-a2d4-ef610f387923"}}]
10:20:13 INFO - 1500657613591 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"1454ef54-bd46-4acd-a2d4-ef610f387923"}]
10:20:13 INFO - 1500657613631 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/clicks.html"
10:20:13 INFO - 1500657613638 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/clicks.html"
10:20:13 INFO - 1500657613639 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/clicks.html"
10:20:13 INFO - 1500657613656 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49346/test.html"
10:20:13 INFO - 1500657613659 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49346/test.html"
10:20:13 INFO - 1500657613662 Marionette TRACE 358 <- [1,4,null,{}]
10:20:13 INFO - 1500657613671 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:20:13 INFO - 1500657613674 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"900a2635-1698-45bf-85dc-4771e2e1aea0","ELEMENT":"900a2635-1698-45bf-85dc-4771e2e1aea0"}}]
10:20:13 INFO - 1500657613675 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:20:13 INFO - 1500657613675 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:20:13 INFO - 1500657613677 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}]
10:20:13 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500657613680 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:20:13 INFO - 1500657613682 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:20:13 INFO - 1500657613683 Marionette TRACE 358 <- [1,8,null,{}]
10:20:13 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 192ms
10:20:13 INFO - TEST-START | test_click.py TestClick.test_container_element
10:20:13 INFO - 1500657613685 Marionette DEBUG Closed connection 358
10:20:13 INFO - 1500657613686 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49788
10:20:13 INFO - 1500657613687 Marionette DEBUG Closed connection 359
10:20:13 INFO - 1500657613688 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49789
10:20:13 INFO - 1500657613689 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:13 INFO - 1500657613689 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:13 INFO - 1500657613691 Marionette DEBUG Register listener.js for window 15
10:20:13 INFO - 1500657613694 Marionette TRACE 360 <- [1,1,null,{"sessionId":"a30b8cf4-0943-40ff-87e1-54d9e0611dba","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:13 INFO - 1500657613696 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:20:13 INFO - 1500657613697 Marionette TRACE 360 <- [1,2,null,{}]
10:20:13 INFO - 1500657613698 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:20:13 INFO - 1500657613698 Marionette TRACE 360 <- [1,3,null,{}]
10:20:13 INFO - 1500657613699 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:20:13 INFO - 1500657613699 Marionette TRACE 360 <- [1,4,null,{}]
10:20:13 INFO - 1500657613701 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}]
10:20:13 INFO - TEST-START: test_click.py TestClick.test_container_element1500657613703 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:20:13 INFO - 1500657613704 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:20:13 INFO - 1500657613705 Marionette TRACE 360 <- [1,6,null,{}]
10:20:13 INFO - 1500657613707 Marionette DEBUG Closed connection 360
10:20:13 INFO - 1500657613708 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49790
10:20:13 INFO - 1500657613708 Marionette DEBUG Closed connection 361
10:20:13 INFO - 1500657613709 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49791
10:20:13 INFO - 1500657613710 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:13 INFO - 1500657613711 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:13 INFO - 1500657613713 Marionette DEBUG Register listener.js for window 15
10:20:13 INFO - 1500657613715 Marionette TRACE 362 <- [1,1,null,{"sessionId":"5c394dbf-7c29-4880-948f-82b752d665eb","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:13 INFO - 1500657613717 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:20:13 INFO - 1500657613718 Marionette TRACE 362 <- [1,2,null,{}]
10:20:13 INFO - 1500657613720 Marionette DEBUG Closed connection 362
10:20:13 INFO - 1500657613721 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49792
10:20:13 INFO - 1500657613722 Marionette DEBUG Closed connection 363
10:20:13 INFO - 1500657613722 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49793
10:20:13 INFO - 1500657613723 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:20:13 INFO - 1500657613724 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:13 INFO - 1500657613726 Marionette DEBUG Register listener.js for window 15
10:20:13 INFO - 1500657613728 Marionette TRACE 364 <- [1,1,null,{"sessionId":"6f28df47-a517-4cff-8132-b498e5ab791c","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:13 INFO - 1500657613730 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"}]
10:20:13 INFO - 1500657613732 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/test.html"
10:20:13 INFO - 1500657613733 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/test.html"
10:20:13 INFO - 1500657613734 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/test.html"
10:20:13 INFO - 1500657613755 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"
10:20:13 INFO - 1500657613756 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"
10:20:13 INFO - 1500657613759 Marionette TRACE 364 <- [1,2,null,{}]
10:20:13 INFO - 1500657613761 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:20:13 INFO - 1500657613771 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f7bde35-ade5-4d1a-ae80-0254dc6715ae","ELEMENT":"4f7bde35-ade5-4d1a-ae80-0254dc6715ae"}}]
10:20:13 INFO - 1500657613772 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"4f7bde35-ade5-4d1a-ae80-0254dc6715ae"}]
10:20:14 INFO - 1500657614012 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:20:14 INFO - 1500657614013 Marionette TRACE 364 <- [1,4,null,{}]
10:20:14 INFO - 1500657614014 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"4f7bde35-ade5-4d1a-ae80-0254dc6715ae","name":"selected"}]
10:20:14 INFO - 1500657614018 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:20:14 INFO - 1500657614020 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}]
10:20:14 INFO - TEST-END: test_click.py TestClick.test_container_element1500657614023 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:20:14 INFO - 1500657614023 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:20:14 INFO - 1500657614025 Marionette TRACE 364 <- [1,7,null,{}]
10:20:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 341ms
10:20:14 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:20:14 INFO - 1500657614027 Marionette DEBUG Closed connection 364
10:20:14 INFO - 1500657614028 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49794
10:20:14 INFO - 1500657614028 Marionette DEBUG Closed connection 365
10:20:14 INFO - 1500657614029 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49795
10:20:14 INFO - 1500657614030 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:14 INFO - 1500657614031 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614033 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614036 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ee4e7201-cf8a-4863-b903-f9fd3b7c8bb9","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614038 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:20:14 INFO - 1500657614038 Marionette TRACE 366 <- [1,2,null,{}]
10:20:14 INFO - 1500657614039 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:20:14 INFO - 1500657614040 Marionette TRACE 366 <- [1,3,null,{}]
10:20:14 INFO - 1500657614040 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:20:14 INFO - 1500657614041 Marionette TRACE 366 <- [1,4,null,{}]
10:20:14 INFO - 1500657614042 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}]
10:20:14 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500657614046 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:20:14 INFO - 1500657614047 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:20:14 INFO - 1500657614048 Marionette TRACE 366 <- [1,6,null,{}]
10:20:14 INFO - 1500657614051 Marionette DEBUG Closed connection 366
10:20:14 INFO - 1500657614052 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49796
10:20:14 INFO - 1500657614052 Marionette DEBUG Closed connection 367
10:20:14 INFO - 1500657614053 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49797
10:20:14 INFO - 1500657614054 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:14 INFO - 1500657614064 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614066 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614069 Marionette TRACE 368 <- [1,1,null,{"sessionId":"ab116be1-7555-4b72-89e3-91ead78a436c","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614071 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:20:14 INFO - 1500657614073 Marionette TRACE 368 <- [1,2,null,{}]
10:20:14 INFO - 1500657614074 Marionette DEBUG Closed connection 368
10:20:14 INFO - 1500657614075 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49798
10:20:14 INFO - 1500657614076 Marionette DEBUG Closed connection 369
10:20:14 INFO - 1500657614077 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49799
10:20:14 INFO - 1500657614078 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:20:14 INFO - 1500657614078 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614080 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614083 Marionette TRACE 370 <- [1,1,null,{"sessionId":"49f08fdd-14c8-4e6d-b2ae-c9c212202cf2","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614085 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"}]
10:20:14 INFO - 1500657614086 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"
10:20:14 INFO - 1500657614089 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"
10:20:14 INFO - 1500657614090 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"
10:20:14 INFO - 1500657614118 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"
10:20:14 INFO - 1500657614122 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"
10:20:14 INFO - 1500657614127 Marionette TRACE 370 <- [1,2,null,{}]
10:20:14 INFO - 1500657614133 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:20:14 INFO - 1500657614136 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8de98848-5e4d-4125-9c10-5ab742f1c822","ELEMENT":"8de98848-5e4d-4125-9c10-5ab742f1c822"}}]
10:20:14 INFO - 1500657614137 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"8de98848-5e4d-4125-9c10-5ab742f1c822"}]
10:20:14 INFO - 1500657614379 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:20:14 INFO - 1500657614380 Marionette TRACE 370 <- [1,4,null,{}]
10:20:14 INFO - 1500657614381 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"8de98848-5e4d-4125-9c10-5ab742f1c822","name":"selected"}]
10:20:14 INFO - 1500657614385 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:20:14 INFO - 1500657614387 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}]
10:20:14 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500657614389 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:20:14 INFO - 1500657614390 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:20:14 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 367ms
10:20:14 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:20:14 INFO - 1500657614392 Marionette TRACE 370 <- [1,7,null,{}]
10:20:14 INFO - 1500657614394 Marionette DEBUG Closed connection 370
10:20:14 INFO - 1500657614395 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49800
10:20:14 INFO - 1500657614395 Marionette DEBUG Closed connection 371
10:20:14 INFO - 1500657614396 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49801
10:20:14 INFO - 1500657614397 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:14 INFO - 1500657614398 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614400 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614403 Marionette TRACE 372 <- [1,1,null,{"sessionId":"7f795391-2261-4c79-a257-05f0d8e32602","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614404 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:20:14 INFO - 1500657614405 Marionette TRACE 372 <- [1,2,null,{}]
10:20:14 INFO - 1500657614406 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:20:14 INFO - 1500657614406 Marionette TRACE 372 <- [1,3,null,{}]
10:20:14 INFO - 1500657614407 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:20:14 INFO - 1500657614408 Marionette TRACE 372 <- [1,4,null,{}]
10:20:14 INFO - 1500657614409 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}]
10:20:14 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500657614412 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:20:14 INFO - 1500657614413 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:20:14 INFO - 1500657614414 Marionette TRACE 372 <- [1,6,null,{}]
10:20:14 INFO - 1500657614416 Marionette DEBUG Closed connection 372
10:20:14 INFO - 1500657614416 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49802
10:20:14 INFO - 1500657614417 Marionette DEBUG Closed connection 373
10:20:14 INFO - 1500657614418 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49803
10:20:14 INFO - 1500657614419 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:14 INFO - 1500657614419 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614421 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614424 Marionette TRACE 374 <- [1,1,null,{"sessionId":"40f8819a-b858-482c-8661-0b60aae1363a","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614426 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:20:14 INFO - 1500657614427 Marionette TRACE 374 <- [1,2,null,{}]
10:20:14 INFO - 1500657614429 Marionette DEBUG Closed connection 374
10:20:14 INFO - 1500657614430 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49804
10:20:14 INFO - 1500657614431 Marionette DEBUG Closed connection 375
10:20:14 INFO - 1500657614431 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49805
10:20:14 INFO - 1500657614432 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:20:14 INFO - 1500657614433 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614435 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614438 Marionette TRACE 376 <- [1,1,null,{"sessionId":"89e30484-8f20-4ea4-aefc-e5a60d21ed20","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614440 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"}]
10:20:14 INFO - 1500657614441 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"
10:20:14 INFO - 1500657614443 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"
10:20:14 INFO - 1500657614444 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"
10:20:14 INFO - 1500657614464 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"
10:20:14 INFO - 1500657614466 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"
10:20:14 INFO - 1500657614470 Marionette TRACE 376 <- [1,2,null,{}]
10:20:14 INFO - 1500657614471 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:20:14 INFO - 1500657614474 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"df0f8966-3995-4fe1-bb7d-3c2f87059587","ELEMENT":"df0f8966-3995-4fe1-bb7d-3c2f87059587"}}]
10:20:14 INFO - 1500657614475 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"df0f8966-3995-4fe1-bb7d-3c2f87059587"}]
10:20:14 INFO - 1500657614716 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:20:14 INFO - 1500657614717 Marionette TRACE 376 <- [1,4,null,{}]
10:20:14 INFO - 1500657614718 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}]
10:20:14 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500657614721 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:20:14 INFO - 1500657614722 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:20:14 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 332ms
10:20:14 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:20:14 INFO - 1500657614724 Marionette TRACE 376 <- [1,6,null,{}]
10:20:14 INFO - 1500657614725 Marionette DEBUG Closed connection 376
10:20:14 INFO - 1500657614726 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49806
10:20:14 INFO - 1500657614727 Marionette DEBUG Closed connection 377
10:20:14 INFO - 1500657614728 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49807
10:20:14 INFO - 1500657614729 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:14 INFO - 1500657614729 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614732 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614734 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d57882af-991f-490c-8e65-27ea28069f9f","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614736 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:20:14 INFO - 1500657614737 Marionette TRACE 378 <- [1,2,null,{}]
10:20:14 INFO - 1500657614738 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:20:14 INFO - 1500657614738 Marionette TRACE 378 <- [1,3,null,{}]
10:20:14 INFO - 1500657614739 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:20:14 INFO - 1500657614739 Marionette TRACE 378 <- [1,4,null,{}]
10:20:14 INFO - 1500657614741 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}]
10:20:14 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500657614743 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:20:14 INFO - 1500657614744 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:20:14 INFO - 1500657614746 Marionette TRACE 378 <- [1,6,null,{}]
10:20:14 INFO - 1500657614747 Marionette DEBUG Closed connection 378
10:20:14 INFO - 1500657614748 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49808
10:20:14 INFO - 1500657614749 Marionette DEBUG Closed connection 379
10:20:14 INFO - 1500657614749 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49809
10:20:14 INFO - 1500657614750 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:14 INFO - 1500657614751 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614753 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614764 Marionette TRACE 380 <- [1,1,null,{"sessionId":"1d44cd03-f317-4f24-bd93-b768cadd3c29","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614766 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:20:14 INFO - 1500657614767 Marionette TRACE 380 <- [1,2,null,{}]
10:20:14 INFO - 1500657614769 Marionette DEBUG Closed connection 380
10:20:14 INFO - 1500657614770 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49810
10:20:14 INFO - 1500657614770 Marionette DEBUG Closed connection 381
10:20:14 INFO - 1500657614771 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49811
10:20:14 INFO - 1500657614772 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:20:14 INFO - 1500657614773 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:14 INFO - 1500657614775 Marionette DEBUG Register listener.js for window 15
10:20:14 INFO - 1500657614777 Marionette TRACE 382 <- [1,1,null,{"sessionId":"262fe024-bba1-4297-8d12-0355fd8031e8","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:14 INFO - 1500657614782 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"}]
10:20:14 INFO - 1500657614784 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"
10:20:14 INFO - 1500657614786 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"
10:20:14 INFO - 1500657614786 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"
10:20:14 INFO - 1500657614803 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"
10:20:14 INFO - 1500657614805 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"
10:20:14 INFO - 1500657614808 Marionette TRACE 382 <- [1,2,null,{}]
10:20:14 INFO - 1500657614809 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:20:14 INFO - 1500657614817 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f9b6011-db18-4855-ba98-39b00ff99236","ELEMENT":"2f9b6011-db18-4855-ba98-39b00ff99236"}}]
10:20:14 INFO - 1500657614819 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"2f9b6011-db18-4855-ba98-39b00ff99236"}]
10:20:15 INFO - 1500657615059 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:20:15 INFO - 1500657615060 Marionette TRACE 382 <- [1,4,null,{}]
10:20:15 INFO - 1500657615062 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}]
10:20:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500657615065 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:20:15 INFO - 1500657615065 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:20:15 INFO - 1500657615067 Marionette TRACE 382 <- [1,6,null,{}]
10:20:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 343ms
10:20:15 INFO - TEST-START | test_click.py TestClick.test_input_file
10:20:15 INFO - 1500657615069 Marionette DEBUG Closed connection 382
10:20:15 INFO - 1500657615070 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49812
10:20:15 INFO - 1500657615070 Marionette DEBUG Closed connection 383
10:20:15 INFO - 1500657615071 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49813
10:20:15 INFO - 1500657615072 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:15 INFO - 1500657615073 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615075 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615078 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e62885dd-1f30-4038-9c0c-032dbf1b4d04","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615080 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:20:15 INFO - 1500657615080 Marionette TRACE 384 <- [1,2,null,{}]
10:20:15 INFO - 1500657615081 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:20:15 INFO - 1500657615081 Marionette TRACE 384 <- [1,3,null,{}]
10:20:15 INFO - 1500657615082 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:20:15 INFO - 1500657615082 Marionette TRACE 384 <- [1,4,null,{}]
10:20:15 INFO - 1500657615084 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}]
10:20:15 INFO - TEST-START: test_click.py TestClick.test_input_file1500657615086 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:20:15 INFO - 1500657615087 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:20:15 INFO - 1500657615089 Marionette TRACE 384 <- [1,6,null,{}]
10:20:15 INFO - 1500657615090 Marionette DEBUG Closed connection 384
10:20:15 INFO - 1500657615091 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49814
10:20:15 INFO - 1500657615092 Marionette DEBUG Closed connection 385
10:20:15 INFO - 1500657615093 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49815
10:20:15 INFO - 1500657615093 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:15 INFO - 1500657615094 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615096 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615099 Marionette TRACE 386 <- [1,1,null,{"sessionId":"a7809d2a-feec-4546-87f1-7e36715c5b4d","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615101 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:20:15 INFO - 1500657615102 Marionette TRACE 386 <- [1,2,null,{}]
10:20:15 INFO - 1500657615104 Marionette DEBUG Closed connection 386
10:20:15 INFO - 1500657615105 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49816
10:20:15 INFO - 1500657615105 Marionette DEBUG Closed connection 387
10:20:15 INFO - 1500657615106 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49817
10:20:15 INFO - 1500657615107 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:20:15 INFO - 1500657615108 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615110 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615113 Marionette TRACE 388 <- [1,1,null,{"sessionId":"6433e7dc-e070-4d2f-b687-893936fee1e8","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615114 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:20:15 INFO - 1500657615116 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"
10:20:15 INFO - 1500657615118 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"
10:20:15 INFO - 1500657615118 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"
10:20:15 INFO - 1500657615138 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:20:15 INFO - 1500657615139 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:20:15 INFO - 1500657615143 Marionette TRACE 388 <- [1,2,null,{}]
10:20:15 INFO - 1500657615151 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:20:15 INFO - 1500657615154 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6b6170f6-01aa-4426-8f1b-9ea9f9e8c9cd","ELEMENT":"6b6170f6-01aa-4426-8f1b-9ea9f9e8c9cd"}}]
10:20:15 INFO - 1500657615155 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6b6170f6-01aa-4426-8f1b-9ea9f9e8c9cd"}]
10:20:15 INFO - 1500657615183 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]
10:20:15 INFO - 1500657615185 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}]
10:20:15 INFO - TEST-END: test_click.py TestClick.test_input_file1500657615188 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:20:15 INFO - 1500657615189 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:20:15 INFO - 1500657615190 Marionette TRACE 388 <- [1,6,null,{}]
10:20:15 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 123ms
10:20:15 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:20:15 INFO - 1500657615192 Marionette DEBUG Closed connection 388
10:20:15 INFO - 1500657615193 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49818
10:20:15 INFO - 1500657615194 Marionette DEBUG Closed connection 389
10:20:15 INFO - 1500657615195 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49819
10:20:15 INFO - 1500657615196 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:15 INFO - 1500657615196 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615199 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615202 Marionette TRACE 390 <- [1,1,null,{"sessionId":"f6359b19-525a-4f3d-a7ea-4dc572afcb54","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615203 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:20:15 INFO - 1500657615204 Marionette TRACE 390 <- [1,2,null,{}]
10:20:15 INFO - 1500657615205 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:20:15 INFO - 1500657615205 Marionette TRACE 390 <- [1,3,null,{}]
10:20:15 INFO - 1500657615206 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:20:15 INFO - 1500657615206 Marionette TRACE 390 <- [1,4,null,{}]
10:20:15 INFO - 1500657615208 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}]
10:20:15 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500657615211 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:20:15 INFO - 1500657615212 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:20:15 INFO - 1500657615213 Marionette TRACE 390 <- [1,6,null,{}]
10:20:15 INFO - 1500657615215 Marionette DEBUG Closed connection 390
10:20:15 INFO - 1500657615215 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49820
10:20:15 INFO - 1500657615216 Marionette DEBUG Closed connection 391
10:20:15 INFO - 1500657615217 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49821
10:20:15 INFO - 1500657615218 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:15 INFO - 1500657615218 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615221 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615224 Marionette TRACE 392 <- [1,1,null,{"sessionId":"227fbfbd-4d0d-4417-bec7-9881aaebc1a9","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615226 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:20:15 INFO - 1500657615227 Marionette TRACE 392 <- [1,2,null,{}]
10:20:15 INFO - 1500657615228 Marionette DEBUG Closed connection 392
10:20:15 INFO - 1500657615229 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49822
10:20:15 INFO - 1500657615230 Marionette DEBUG Closed connection 393
10:20:15 INFO - 1500657615231 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49823
10:20:15 INFO - 1500657615232 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:20:15 INFO - 1500657615232 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615243 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615246 Marionette TRACE 394 <- [1,1,null,{"sessionId":"4facec8c-4037-4b5e-9ad4-3c3ae7baf62c","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615249 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"}]
10:20:15 INFO - 1500657615250 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:20:15 INFO - 1500657615252 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:20:15 INFO - 1500657615253 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:20:15 INFO - 1500657615276 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"
10:20:15 INFO - 1500657615277 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"
10:20:15 INFO - 1500657615280 Marionette TRACE 394 <- [1,2,null,{}]
10:20:15 INFO - 1500657615288 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:20:15 INFO - 1500657615291 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"90c3b4aa-8081-46a2-9ff2-9406550193f4","ELEMENT":"90c3b4aa-8081-46a2-9ff2-9406550193f4"}}]
10:20:15 INFO - 1500657615292 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:20:15 INFO - 1500657615294 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5e214b50-6019-4346-9374-7cae8ea7e51e","ELEMENT":"5e214b50-6019-4346-9374-7cae8ea7e51e"}}]
10:20:15 INFO - 1500657615295 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"90c3b4aa-8081-46a2-9ff2-9406550193f4"}]
10:20:15 INFO - 1500657615534 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:20:15 INFO - 1500657615535 Marionette TRACE 394 <- [1,5,null,{}]
10:20:15 INFO - 1500657615536 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"5e214b50-6019-4346-9374-7cae8ea7e51e"}]
10:20:15 INFO - 1500657615551 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:20:15 INFO - 1500657615553 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
10:20:15 INFO - 1500657615555 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:20:15 INFO - 1500657615557 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}]
10:20:15 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500657615559 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:20:15 INFO - 1500657615560 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:20:15 INFO - 1500657615561 Marionette TRACE 394 <- [1,9,null,{}]
10:20:15 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 370ms
10:20:15 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:20:15 INFO - 1500657615563 Marionette DEBUG Closed connection 394
10:20:15 INFO - 1500657615564 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49824
10:20:15 INFO - 1500657615565 Marionette DEBUG Closed connection 395
10:20:15 INFO - 1500657615566 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49825
10:20:15 INFO - 1500657615567 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:15 INFO - 1500657615568 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615570 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615573 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a930167e-fc7e-4985-9504-23519a690804","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615575 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:20:15 INFO - 1500657615576 Marionette TRACE 396 <- [1,2,null,{}]
10:20:15 INFO - 1500657615576 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:20:15 INFO - 1500657615577 Marionette TRACE 396 <- [1,3,null,{}]
10:20:15 INFO - 1500657615578 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:20:15 INFO - 1500657615578 Marionette TRACE 396 <- [1,4,null,{}]
10:20:15 INFO - 1500657615580 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}]
10:20:15 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500657615583 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:20:15 INFO - 1500657615583 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:20:15 INFO - 1500657615585 Marionette TRACE 396 <- [1,6,null,{}]
10:20:15 INFO - 1500657615586 Marionette DEBUG Closed connection 396
10:20:15 INFO - 1500657615587 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49826
10:20:15 INFO - 1500657615588 Marionette DEBUG Closed connection 397
10:20:15 INFO - 1500657615589 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49827
10:20:15 INFO - 1500657615590 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:20:15 INFO - 1500657615590 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615592 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615595 Marionette TRACE 398 <- [1,1,null,{"sessionId":"1dd2f747-cba5-44a9-b1b9-fdbb7a29d5f3","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615597 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:20:15 INFO - 1500657615598 Marionette TRACE 398 <- [1,2,null,{}]
10:20:15 INFO - 1500657615600 Marionette DEBUG Closed connection 398
10:20:15 INFO - 1500657615600 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49828
10:20:15 INFO - 1500657615601 Marionette DEBUG Closed connection 399
10:20:15 INFO - 1500657615602 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49829
10:20:15 INFO - 1500657615603 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:20:15 INFO - 1500657615603 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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}
10:20:15 INFO - 1500657615605 Marionette DEBUG Register listener.js for window 15
10:20:15 INFO - 1500657615608 Marionette TRACE 400 <- [1,1,null,{"sessionId":"ea7f9658-25e6-42f2-a5bd-7112ea3caf1e","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":3488,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprhzaxx.mozrunner","moz:accessibilityChecks":false}}]
10:20:15 INFO - 1500657615610 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"}]
10:20:15 INFO - 1500657615611 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"
10:20:15 INFO - 1500657615614 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"
10:20:15 INFO - 1500657615614 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"
10:20:15 INFO - 1500657615626 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"
10:20:15 INFO - 1500657615630 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"
10:20:15 INFO - 1500657615641 Marionette TRACE 400 <- [1,2,null,{}]
10:20:15 INFO - 1500657615642 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:20:15 INFO - 1500657615646 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf0c531c-4eea-40ea-8013-75357e892e86","ELEMENT":"cf0c531c-4eea-40ea-8013-75357e892e86"}}]
10:20:15 INFO - 1500657615647 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"cf0c531c-4eea-40ea-8013-75357e892e86"}]
10:20:15 INFO - 1500657615670 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:20:15 INFO - 1500657615671 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"cf0c531c-4eea-40ea-8013-75357e892e86"}]
10:20:15 INFO - 1500657615690 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element