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:54:45 INFO - 1500486885468 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:54:45 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500486885471 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:54:45 INFO - 1500486885472 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:54:45 INFO - 1500486885474 Marionette TRACE 352 <- [1,6,null,{}]
10:54:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 144ms
10:54:45 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:54:45 INFO - 1500486885476 Marionette DEBUG Closed connection 352
10:54:45 INFO - 1500486885477 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49794
10:54:45 INFO - 1500486885477 Marionette DEBUG Closed connection 353
10:54:45 INFO - 1500486885478 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49795
10:54:45 INFO - 1500486885479 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:45 INFO - 1500486885480 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:45 INFO - 1500486885483 Marionette DEBUG Register listener.js for window 6442450945
10:54:45 INFO - 1500486885488 Marionette TRACE 354 <- [1,1,null,{"sessionId":"81e674ff-bdaf-4667-babb-622988b9108b","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:45 INFO - 1500486885489 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:54:45 INFO - 1500486885489 Marionette TRACE 354 <- [1,2,null,{}]
10:54:45 INFO - 1500486885490 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:54:45 INFO - 1500486885491 Marionette TRACE 354 <- [1,3,null,{}]
10:54:45 INFO - 1500486885492 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:54:45 INFO - 1500486885493 Marionette TRACE 354 <- [1,4,null,{}]
10:54:45 INFO - 1500486885495 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:54:45 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500486885498 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:54:45 INFO - 1500486885499 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:54:45 INFO - 1500486885500 Marionette TRACE 354 <- [1,6,null,{}]
10:54:45 INFO - 1500486885502 Marionette DEBUG Closed connection 354
10:54:45 INFO - 1500486885503 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49796
10:54:45 INFO - 1500486885504 Marionette DEBUG Closed connection 355
10:54:45 INFO - 1500486885504 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49797
10:54:45 INFO - 1500486885506 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:45 INFO - 1500486885506 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:45 INFO - 1500486885509 Marionette DEBUG Register listener.js for window 6442450945
10:54:45 INFO - 1500486885513 Marionette TRACE 356 <- [1,1,null,{"sessionId":"1698275d-6b20-480b-bdc2-bdc04d2519e8","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:45 INFO - 1500486885514 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:54:45 INFO - 1500486885516 Marionette TRACE 356 <- [1,2,null,{}]
10:54:45 INFO - 1500486885518 Marionette DEBUG Closed connection 356
10:54:45 INFO - 1500486885519 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49798
10:54:45 INFO - 1500486885519 Marionette DEBUG Closed connection 357
10:54:45 INFO - 1500486885520 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49799
10:54:45 INFO - 1500486885522 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:54:45 INFO - 1500486885522 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:45 INFO - 1500486885525 Marionette DEBUG Register listener.js for window 6442450945
10:54:45 INFO - 1500486885528 Marionette TRACE 358 <- [1,1,null,{"sessionId":"efbe1f9d-3fe8-4edf-953b-f4ed80a26d28","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:45 INFO - 1500486885530 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49350/clicks.html"}]
10:54:45 INFO - 1500486885532 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49350/hidden.html"
10:54:45 INFO - 1500486885542 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49350/hidden.html"
10:54:45 INFO - 1500486885543 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49350/hidden.html"
10:54:45 INFO - 1500486885550 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49350/clicks.html"
10:54:45 INFO - 1500486885556 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49350/clicks.html"
10:54:45 INFO - 1500486885560 Marionette TRACE 358 <- [1,2,null,{}]
10:54:45 INFO - 1500486885565 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:54:45 INFO - 1500486885569 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5fc15ad1-4d02-424f-a5f2-398fbe70776f","ELEMENT":"5fc15ad1-4d02-424f-a5f2-398fbe70776f"}}]
10:54:45 INFO - 1500486885570 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5fc15ad1-4d02-424f-a5f2-398fbe70776f"}]
10:54:45 INFO - 1500486885622 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49350/clicks.html"
10:54:45 INFO - 1500486885631 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49350/clicks.html"
10:54:45 INFO - 1500486885631 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49350/clicks.html"
10:54:45 INFO - 1500486885649 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49350/test.html"
10:54:45 INFO - 1500486885651 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49350/test.html"
10:54:45 INFO - 1500486885659 Marionette TRACE 358 <- [1,4,null,{}]
10:54:45 INFO - 1500486885667 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:54:45 INFO - 1500486885675 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"401cd461-cfee-49bd-8c67-e74f3f1203ed","ELEMENT":"401cd461-cfee-49bd-8c67-e74f3f1203ed"}}]
10:54:45 INFO - 1500486885677 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:54:45 INFO - 1500486885677 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:54:45 INFO - 1500486885679 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:54:45 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500486885682 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:54:45 INFO - 1500486885683 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:54:45 INFO - 1500486885685 Marionette TRACE 358 <- [1,8,null,{}]
10:54:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 211ms
10:54:45 INFO - TEST-START | test_click.py TestClick.test_container_element
10:54:45 INFO - 1500486885687 Marionette DEBUG Closed connection 358
10:54:45 INFO - 1500486885688 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49802
10:54:45 INFO - 1500486885689 Marionette DEBUG Closed connection 359
10:54:45 INFO - 1500486885689 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49803
10:54:45 INFO - 1500486885690 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:45 INFO - 1500486885691 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:45 INFO - 1500486885694 Marionette DEBUG Register listener.js for window 6442450945
10:54:45 INFO - 1500486885699 Marionette TRACE 360 <- [1,1,null,{"sessionId":"c77bd7fc-8844-4ff2-8bb4-cf4af539f062","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:45 INFO - 1500486885700 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:54:45 INFO - 1500486885701 Marionette TRACE 360 <- [1,2,null,{}]
10:54:45 INFO - 1500486885702 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:54:45 INFO - 1500486885702 Marionette TRACE 360 <- [1,3,null,{}]
10:54:45 INFO - 1500486885703 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:54:45 INFO - 1500486885704 Marionette TRACE 360 <- [1,4,null,{}]
10:54:45 INFO - 1500486885705 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:54:45 INFO - TEST-START: test_click.py TestClick.test_container_element1500486885708 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:54:45 INFO - 1500486885710 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:54:45 INFO - 1500486885711 Marionette TRACE 360 <- [1,6,null,{}]
10:54:45 INFO - 1500486885713 Marionette DEBUG Closed connection 360
10:54:45 INFO - 1500486885714 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49804
10:54:45 INFO - 1500486885714 Marionette DEBUG Closed connection 361
10:54:45 INFO - 1500486885715 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49805
10:54:45 INFO - 1500486885716 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:45 INFO - 1500486885717 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:45 INFO - 1500486885720 Marionette DEBUG Register listener.js for window 6442450945
10:54:45 INFO - 1500486885723 Marionette TRACE 362 <- [1,1,null,{"sessionId":"41c17be4-6dbc-48f6-aa94-0afd7e878500","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:45 INFO - 1500486885724 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:54:45 INFO - 1500486885726 Marionette TRACE 362 <- [1,2,null,{}]
10:54:45 INFO - 1500486885728 Marionette DEBUG Closed connection 362
10:54:45 INFO - 1500486885728 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49806
10:54:45 INFO - 1500486885729 Marionette DEBUG Closed connection 363
10:54:45 INFO - 1500486885730 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49807
10:54:45 INFO - 1500486885731 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:54:45 INFO - 1500486885732 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:45 INFO - 1500486885735 Marionette DEBUG Register listener.js for window 6442450945
10:54:45 INFO - 1500486885738 Marionette TRACE 364 <- [1,1,null,{"sessionId":"04e7b0dd-23e3-4563-a4c7-52e28726c778","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:45 INFO - 1500486885739 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:54:45 INFO - 1500486885741 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49350/test.html"
10:54:45 INFO - 1500486885743 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49350/test.html"
10:54:45 INFO - 1500486885744 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49350/test.html"
10:54:45 INFO - 1500486885751 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:54:45 INFO - 1500486885754 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:54:45 INFO - 1500486885765 Marionette TRACE 364 <- [1,2,null,{}]
10:54:45 INFO - 1500486885775 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:54:45 INFO - 1500486885780 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5e74d3a1-abe2-4a5f-aa17-41ee5dc3bbf7","ELEMENT":"5e74d3a1-abe2-4a5f-aa17-41ee5dc3bbf7"}}]
10:54:45 INFO - 1500486885781 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"5e74d3a1-abe2-4a5f-aa17-41ee5dc3bbf7"}]
10:54:46 INFO - 1500486886028 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:54:46 INFO - 1500486886029 Marionette TRACE 364 <- [1,4,null,{}]
10:54:46 INFO - 1500486886031 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"5e74d3a1-abe2-4a5f-aa17-41ee5dc3bbf7","name":"selected"}]
10:54:46 INFO - 1500486886037 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:54:46 INFO - 1500486886039 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:54:46 INFO - TEST-END: test_click.py TestClick.test_container_element1500486886042 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:54:46 INFO - 1500486886043 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:54:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 360ms
10:54:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:54:46 INFO - 1500486886045 Marionette TRACE 364 <- [1,7,null,{}]
10:54:46 INFO - 1500486886047 Marionette DEBUG Closed connection 364
10:54:46 INFO - 1500486886048 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49808
10:54:46 INFO - 1500486886049 Marionette DEBUG Closed connection 365
10:54:46 INFO - 1500486886050 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49809
10:54:46 INFO - 1500486886051 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:46 INFO - 1500486886052 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886055 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886058 Marionette TRACE 366 <- [1,1,null,{"sessionId":"064fbac1-42d7-4a76-bab7-6f571f95d7e0","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886060 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:54:46 INFO - 1500486886060 Marionette TRACE 366 <- [1,2,null,{}]
10:54:46 INFO - 1500486886061 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:54:46 INFO - 1500486886062 Marionette TRACE 366 <- [1,3,null,{}]
10:54:46 INFO - 1500486886063 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:54:46 INFO - 1500486886064 Marionette TRACE 366 <- [1,4,null,{}]
10:54:46 INFO - 1500486886065 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:54:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500486886069 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:54:46 INFO - 1500486886070 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:54:46 INFO - 1500486886071 Marionette TRACE 366 <- [1,6,null,{}]
10:54:46 INFO - 1500486886073 Marionette DEBUG Closed connection 366
10:54:46 INFO - 1500486886074 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49810
10:54:46 INFO - 1500486886075 Marionette DEBUG Closed connection 367
10:54:46 INFO - 1500486886076 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49811
10:54:46 INFO - 1500486886077 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:46 INFO - 1500486886077 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886080 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886084 Marionette TRACE 368 <- [1,1,null,{"sessionId":"677d483c-e4a6-471a-9a90-641331f5b554","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886085 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:54:46 INFO - 1500486886086 Marionette TRACE 368 <- [1,2,null,{}]
10:54:46 INFO - 1500486886088 Marionette DEBUG Closed connection 368
10:54:46 INFO - 1500486886089 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49812
10:54:46 INFO - 1500486886090 Marionette DEBUG Closed connection 369
10:54:46 INFO - 1500486886091 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49813
10:54:46 INFO - 1500486886092 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:54:46 INFO - 1500486886092 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886095 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886099 Marionette TRACE 370 <- [1,1,null,{"sessionId":"286e6c96-a1ad-450e-9672-3becbc80b4cd","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886100 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:54:46 INFO - 1500486886102 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:54:46 INFO - 1500486886106 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:54:46 INFO - 1500486886106 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:54:46 INFO - 1500486886115 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:54:46 INFO - 1500486886121 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:54:46 INFO - 1500486886131 Marionette TRACE 370 <- [1,2,null,{}]
10:54:46 INFO - 1500486886141 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:54:46 INFO - 1500486886144 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4ec6b71d-fb12-44ee-aea6-d785c1a857d2","ELEMENT":"4ec6b71d-fb12-44ee-aea6-d785c1a857d2"}}]
10:54:46 INFO - 1500486886145 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"4ec6b71d-fb12-44ee-aea6-d785c1a857d2"}]
10:54:46 INFO - 1500486886397 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:54:46 INFO - 1500486886398 Marionette TRACE 370 <- [1,4,null,{}]
10:54:46 INFO - 1500486886400 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"4ec6b71d-fb12-44ee-aea6-d785c1a857d2","name":"selected"}]
10:54:46 INFO - 1500486886405 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:54:46 INFO - 1500486886406 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:54:46 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500486886409 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:54:46 INFO - 1500486886410 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:54:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 366ms
10:54:46 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:54:46 INFO - 1500486886412 Marionette TRACE 370 <- [1,7,null,{}]
10:54:46 INFO - 1500486886414 Marionette DEBUG Closed connection 370
10:54:46 INFO - 1500486886415 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49814
10:54:46 INFO - 1500486886415 Marionette DEBUG Closed connection 371
10:54:46 INFO - 1500486886416 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49815
10:54:46 INFO - 1500486886417 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:46 INFO - 1500486886418 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886421 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886432 Marionette TRACE 372 <- [1,1,null,{"sessionId":"0b2e5c8d-c269-4301-9741-5188a017e814","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886434 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:54:46 INFO - 1500486886434 Marionette TRACE 372 <- [1,2,null,{}]
10:54:46 INFO - 1500486886435 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:54:46 INFO - 1500486886436 Marionette TRACE 372 <- [1,3,null,{}]
10:54:46 INFO - 1500486886437 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:54:46 INFO - 1500486886437 Marionette TRACE 372 <- [1,4,null,{}]
10:54:46 INFO - 1500486886439 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:54:46 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500486886443 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:54:46 INFO - 1500486886444 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:54:46 INFO - 1500486886445 Marionette TRACE 372 <- [1,6,null,{}]
10:54:46 INFO - 1500486886447 Marionette DEBUG Closed connection 372
10:54:46 INFO - 1500486886448 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49816
10:54:46 INFO - 1500486886448 Marionette DEBUG Closed connection 373
10:54:46 INFO - 1500486886449 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49817
10:54:46 INFO - 1500486886450 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:46 INFO - 1500486886451 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886454 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886457 Marionette TRACE 374 <- [1,1,null,{"sessionId":"b53a26bd-268f-4750-a813-3a05e28b1817","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886459 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:54:46 INFO - 1500486886460 Marionette TRACE 374 <- [1,2,null,{}]
10:54:46 INFO - 1500486886462 Marionette DEBUG Closed connection 374
10:54:46 INFO - 1500486886462 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49818
10:54:46 INFO - 1500486886463 Marionette DEBUG Closed connection 375
10:54:46 INFO - 1500486886464 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49819
10:54:46 INFO - 1500486886465 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:54:46 INFO - 1500486886466 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886468 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886472 Marionette TRACE 376 <- [1,1,null,{"sessionId":"da53e540-9082-4d95-a389-84167f3296c1","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886473 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:54:46 INFO - 1500486886475 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:54:46 INFO - 1500486886478 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:54:46 INFO - 1500486886478 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:54:46 INFO - 1500486886485 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:54:46 INFO - 1500486886488 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:54:46 INFO - 1500486886499 Marionette TRACE 376 <- [1,2,null,{}]
10:54:46 INFO - 1500486886509 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:54:46 INFO - 1500486886512 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f163c29f-8e63-4968-ad94-0b4714baefba","ELEMENT":"f163c29f-8e63-4968-ad94-0b4714baefba"}}]
10:54:46 INFO - 1500486886513 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f163c29f-8e63-4968-ad94-0b4714baefba"}]
10:54:46 INFO - 1500486886761 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:54:46 INFO - 1500486886762 Marionette TRACE 376 <- [1,4,null,{}]
10:54:46 INFO - 1500486886764 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:54:46 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500486886768 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:54:46 INFO - 1500486886769 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:54:46 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 359ms
10:54:46 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:54:46 INFO - 1500486886770 Marionette TRACE 376 <- [1,6,null,{}]
10:54:46 INFO - 1500486886772 Marionette DEBUG Closed connection 376
10:54:46 INFO - 1500486886773 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49820
10:54:46 INFO - 1500486886774 Marionette DEBUG Closed connection 377
10:54:46 INFO - 1500486886775 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49821
10:54:46 INFO - 1500486886776 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:46 INFO - 1500486886776 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886779 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886783 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ba211800-30da-426c-ad90-ada198db57d1","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886784 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:54:46 INFO - 1500486886785 Marionette TRACE 378 <- [1,2,null,{}]
10:54:46 INFO - 1500486886786 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:54:46 INFO - 1500486886786 Marionette TRACE 378 <- [1,3,null,{}]
10:54:46 INFO - 1500486886787 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:54:46 INFO - 1500486886788 Marionette TRACE 378 <- [1,4,null,{}]
10:54:46 INFO - 1500486886790 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:54:46 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500486886793 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:54:46 INFO - 1500486886794 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:54:46 INFO - 1500486886795 Marionette TRACE 378 <- [1,6,null,{}]
10:54:46 INFO - 1500486886797 Marionette DEBUG Closed connection 378
10:54:46 INFO - 1500486886798 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49822
10:54:46 INFO - 1500486886799 Marionette DEBUG Closed connection 379
10:54:46 INFO - 1500486886799 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49823
10:54:46 INFO - 1500486886801 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:46 INFO - 1500486886801 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886804 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886807 Marionette TRACE 380 <- [1,1,null,{"sessionId":"84c145d4-e76f-46eb-b0ac-7bd9513a48bf","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886809 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:54:46 INFO - 1500486886810 Marionette TRACE 380 <- [1,2,null,{}]
10:54:46 INFO - 1500486886812 Marionette DEBUG Closed connection 380
10:54:46 INFO - 1500486886813 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49824
10:54:46 INFO - 1500486886814 Marionette DEBUG Closed connection 381
10:54:46 INFO - 1500486886814 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49825
10:54:46 INFO - 1500486886815 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:54:46 INFO - 1500486886816 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:46 INFO - 1500486886819 Marionette DEBUG Register listener.js for window 6442450945
10:54:46 INFO - 1500486886822 Marionette TRACE 382 <- [1,1,null,{"sessionId":"7df1ab59-a577-4a1d-bece-f2e147072628","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:46 INFO - 1500486886824 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:54:46 INFO - 1500486886826 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:54:46 INFO - 1500486886828 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:54:46 INFO - 1500486886828 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:54:46 INFO - 1500486886835 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:54:46 INFO - 1500486886839 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:54:46 INFO - 1500486886849 Marionette TRACE 382 <- [1,2,null,{}]
10:54:46 INFO - 1500486886857 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:54:46 INFO - 1500486886861 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5d44a25a-9c4e-4aa4-ad5a-f409e38097e9","ELEMENT":"5d44a25a-9c4e-4aa4-ad5a-f409e38097e9"}}]
10:54:46 INFO - 1500486886862 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"5d44a25a-9c4e-4aa4-ad5a-f409e38097e9"}]
10:54:47 INFO - 1500486887107 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:54:47 INFO - 1500486887108 Marionette TRACE 382 <- [1,4,null,{}]
10:54:47 INFO - 1500486887110 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:54:47 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500486887114 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:54:47 INFO - 1500486887115 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:54:47 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 345ms
10:54:47 INFO - TEST-START | test_click.py TestClick.test_input_file
10:54:47 INFO - 1500486887116 Marionette TRACE 382 <- [1,6,null,{}]
10:54:47 INFO - 1500486887118 Marionette DEBUG Closed connection 382
10:54:47 INFO - 1500486887119 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49826
10:54:47 INFO - 1500486887120 Marionette DEBUG Closed connection 383
10:54:47 INFO - 1500486887120 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49827
10:54:47 INFO - 1500486887122 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:47 INFO - 1500486887122 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887125 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887129 Marionette TRACE 384 <- [1,1,null,{"sessionId":"dd6dfeb3-58c8-4a1a-9844-da6027fdcee9","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887130 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:54:47 INFO - 1500486887131 Marionette TRACE 384 <- [1,2,null,{}]
10:54:47 INFO - 1500486887132 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:54:47 INFO - 1500486887132 Marionette TRACE 384 <- [1,3,null,{}]
10:54:47 INFO - 1500486887133 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:54:47 INFO - 1500486887134 Marionette TRACE 384 <- [1,4,null,{}]
10:54:47 INFO - 1500486887135 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:54:47 INFO - TEST-START: test_click.py TestClick.test_input_file1500486887138 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:54:47 INFO - 1500486887139 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:54:47 INFO - 1500486887141 Marionette TRACE 384 <- [1,6,null,{}]
10:54:47 INFO - 1500486887143 Marionette DEBUG Closed connection 384
10:54:47 INFO - 1500486887143 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49828
10:54:47 INFO - 1500486887144 Marionette DEBUG Closed connection 385
10:54:47 INFO - 1500486887145 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49829
10:54:47 INFO - 1500486887146 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:47 INFO - 1500486887147 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887149 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887153 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f87b6d7a-d083-4c57-8b92-0615a9caf865","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887154 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:54:47 INFO - 1500486887156 Marionette TRACE 386 <- [1,2,null,{}]
10:54:47 INFO - 1500486887158 Marionette DEBUG Closed connection 386
10:54:47 INFO - 1500486887158 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49830
10:54:47 INFO - 1500486887159 Marionette DEBUG Closed connection 387
10:54:47 INFO - 1500486887160 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49831
10:54:47 INFO - 1500486887161 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:54:47 INFO - 1500486887162 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887165 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887168 Marionette TRACE 388 <- [1,1,null,{"sessionId":"189c57f8-6245-47c8-93a6-bb61cd33e186","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887169 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:54:47 INFO - 1500486887171 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:54:47 INFO - 1500486887173 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:54:47 INFO - 1500486887174 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:54:47 INFO - 1500486887180 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:54:47 INFO - 1500486887186 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:54:47 INFO - 1500486887194 Marionette TRACE 388 <- [1,2,null,{}]
10:54:47 INFO - 1500486887198 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:54:47 INFO - 1500486887202 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"052b0623-b71e-43e7-8de7-57d052c9c190","ELEMENT":"052b0623-b71e-43e7-8de7-57d052c9c190"}}]
10:54:47 INFO - 1500486887208 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"052b0623-b71e-43e7-8de7-57d052c9c190"}]
10:54:47 INFO - 1500486887236 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:54:47 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 127ms
10:54:47 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:54:47 INFO - 1500486887238 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:54:47 INFO - TEST-END: test_click.py TestClick.test_input_file1500486887241 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:54:47 INFO - 1500486887242 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:54:47 INFO - 1500486887244 Marionette TRACE 388 <- [1,6,null,{}]
10:54:47 INFO - 1500486887245 Marionette DEBUG Closed connection 388
10:54:47 INFO - 1500486887246 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49832
10:54:47 INFO - 1500486887247 Marionette DEBUG Closed connection 389
10:54:47 INFO - 1500486887248 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49833
10:54:47 INFO - 1500486887249 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:47 INFO - 1500486887250 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887253 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887257 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c13c1e2d-91f1-47f9-ab04-f95fa73f0671","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887258 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:54:47 INFO - 1500486887259 Marionette TRACE 390 <- [1,2,null,{}]
10:54:47 INFO - 1500486887260 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:54:47 INFO - 1500486887260 Marionette TRACE 390 <- [1,3,null,{}]
10:54:47 INFO - 1500486887261 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:54:47 INFO - 1500486887262 Marionette TRACE 390 <- [1,4,null,{}]
10:54:47 INFO - 1500486887263 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:54:47 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500486887266 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:54:47 INFO - 1500486887267 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:54:47 INFO - 1500486887269 Marionette TRACE 390 <- [1,6,null,{}]
10:54:47 INFO - 1500486887271 Marionette DEBUG Closed connection 390
10:54:47 INFO - 1500486887272 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49834
10:54:47 INFO - 1500486887272 Marionette DEBUG Closed connection 391
10:54:47 INFO - 1500486887273 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49835
10:54:47 INFO - 1500486887274 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:47 INFO - 1500486887275 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887278 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887281 Marionette TRACE 392 <- [1,1,null,{"sessionId":"57434502-2c75-45d1-bb79-d5f2c2872580","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887282 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:54:47 INFO - 1500486887284 Marionette TRACE 392 <- [1,2,null,{}]
10:54:47 INFO - 1500486887285 Marionette DEBUG Closed connection 392
10:54:47 INFO - 1500486887286 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49836
10:54:47 INFO - 1500486887287 Marionette DEBUG Closed connection 393
10:54:47 INFO - 1500486887288 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49837
10:54:47 INFO - 1500486887289 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:54:47 INFO - 1500486887290 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887292 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887296 Marionette TRACE 394 <- [1,1,null,{"sessionId":"af2511a7-8459-4447-ac52-c87b8e8b47d5","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887297 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:54:47 INFO - 1500486887299 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:54:47 INFO - 1500486887301 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:54:47 INFO - 1500486887302 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:54:47 INFO - 1500486887309 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:54:47 INFO - 1500486887312 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:54:47 INFO - 1500486887316 Marionette TRACE 394 <- [1,2,null,{}]
10:54:47 INFO - 1500486887328 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:54:47 INFO - 1500486887331 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"13e66101-fe89-440c-a2fc-7d143518bb8a","ELEMENT":"13e66101-fe89-440c-a2fc-7d143518bb8a"}}]
10:54:47 INFO - 1500486887332 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:54:47 INFO - 1500486887335 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f6f9151-a773-45fa-8567-b17f920ae1f1","ELEMENT":"9f6f9151-a773-45fa-8567-b17f920ae1f1"}}]
10:54:47 INFO - 1500486887337 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"13e66101-fe89-440c-a2fc-7d143518bb8a"}]
10:54:47 INFO - 1500486887589 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:54:47 INFO - 1500486887590 Marionette TRACE 394 <- [1,5,null,{}]
10:54:47 INFO - 1500486887592 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9f6f9151-a773-45fa-8567-b17f920ae1f1"}]
10:54:47 INFO - 1500486887610 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:54:47 INFO - 1500486887612 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
10:54:47 INFO - 1500486887615 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:54:47 INFO - 1500486887617 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:54:47 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500486887620 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:54:47 INFO - 1500486887621 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:54:47 INFO - 1500486887623 Marionette TRACE 394 <- [1,9,null,{}]
10:54:47 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 379ms
10:54:47 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:54:47 INFO - 1500486887625 Marionette DEBUG Closed connection 394
10:54:47 INFO - 1500486887625 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49838
10:54:47 INFO - 1500486887626 Marionette DEBUG Closed connection 395
10:54:47 INFO - 1500486887627 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49839
10:54:47 INFO - 1500486887628 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:47 INFO - 1500486887629 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887632 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887636 Marionette TRACE 396 <- [1,1,null,{"sessionId":"75f5713e-bc2c-49b3-bd5b-11ffce50f41c","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887637 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:54:47 INFO - 1500486887637 Marionette TRACE 396 <- [1,2,null,{}]
10:54:47 INFO - 1500486887638 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:54:47 INFO - 1500486887639 Marionette TRACE 396 <- [1,3,null,{}]
10:54:47 INFO - 1500486887640 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:54:47 INFO - 1500486887640 Marionette TRACE 396 <- [1,4,null,{}]
10:54:47 INFO - 1500486887642 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:54:47 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500486887645 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:54:47 INFO - 1500486887646 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:54:47 INFO - 1500486887648 Marionette TRACE 396 <- [1,6,null,{}]
10:54:47 INFO - 1500486887649 Marionette DEBUG Closed connection 396
10:54:47 INFO - 1500486887650 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49840
10:54:47 INFO - 1500486887651 Marionette DEBUG Closed connection 397
10:54:47 INFO - 1500486887652 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49841
10:54:47 INFO - 1500486887654 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:54:47 INFO - 1500486887655 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887658 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887661 Marionette TRACE 398 <- [1,1,null,{"sessionId":"cc9c5b8a-eeb9-4386-98f1-8b963898f6b8","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887663 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:54:47 INFO - 1500486887664 Marionette TRACE 398 <- [1,2,null,{}]
10:54:47 INFO - 1500486887666 Marionette DEBUG Closed connection 398
10:54:47 INFO - 1500486887667 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49842
10:54:47 INFO - 1500486887668 Marionette DEBUG Closed connection 399
10:54:47 INFO - 1500486887668 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49843
10:54:47 INFO - 1500486887670 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:54:47 INFO - 1500486887670 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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}
10:54:47 INFO - 1500486887673 Marionette DEBUG Register listener.js for window 6442450945
10:54:47 INFO - 1500486887677 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c290f864-0b0a-47a9-b929-e50c2ff3e0be","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":5752,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqxmtb.mozrunner","moz:accessibilityChecks":false}}]
10:54:47 INFO - 1500486887678 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:54:47 INFO - 1500486887680 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:54:47 INFO - 1500486887683 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:54:47 INFO - 1500486887683 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:54:47 INFO - 1500486887692 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:54:47 INFO - 1500486887694 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:54:47 INFO - 1500486887699 Marionette TRACE 400 <- [1,2,null,{}]
10:54:47 INFO - 1500486887711 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:54:47 INFO - 1500486887714 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"25649e4a-b41e-48c3-b047-8ddabef8f688","ELEMENT":"25649e4a-b41e-48c3-b047-8ddabef8f688"}}]
10:54:47 INFO - 1500486887715 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"25649e4a-b41e-48c3-b047-8ddabef8f688"}]
10:54:47 INFO - 1500486887740 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:54:47 INFO - 1500486887741 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"25649e4a-b41e-48c3-b047-8ddabef8f688"}]
10:54:47 INFO - 1500486887760 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element