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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
02:50:17 INFO - 1499161817443 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}]
02:50:17 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499161817447 Marionette TRACE 352 <- [1,5,null,{"value":null}]
02:50:17 INFO - 1499161817449 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
02:50:17 INFO - 1499161817450 Marionette TRACE 352 <- [1,6,null,{}]
02:50:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 190ms
02:50:17 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
02:50:17 INFO - 1499161817453 Marionette DEBUG Closed connection 352
02:50:17 INFO - 1499161817454 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49787
02:50:17 INFO - 1499161817455 Marionette DEBUG Closed connection 353
02:50:17 INFO - 1499161817456 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49788
02:50:17 INFO - 1499161817457 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:17 INFO - 1499161817458 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:17 INFO - 1499161817461 Marionette DEBUG Register listener.js for window 12
02:50:17 INFO - 1499161817465 Marionette TRACE 354 <- [1,1,null,{"sessionId":"861c7ce6-f7a7-4c22-8a0e-c30e64c46da2","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:17 INFO - 1499161817466 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
02:50:17 INFO - 1499161817467 Marionette TRACE 354 <- [1,2,null,{}]
02:50:17 INFO - 1499161817468 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:17 INFO - 1499161817468 Marionette TRACE 354 <- [1,3,null,{}]
02:50:17 INFO - 1499161817470 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:17 INFO - 1499161817470 Marionette TRACE 354 <- [1,4,null,{}]
02:50:17 INFO - 1499161817472 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}]
02:50:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499161817475 Marionette TRACE 354 <- [1,5,null,{"value":null}]
02:50:17 INFO - 1499161817476 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
02:50:17 INFO - 1499161817478 Marionette TRACE 354 <- [1,6,null,{}]
02:50:17 INFO - 1499161817481 Marionette DEBUG Closed connection 354
02:50:17 INFO - 1499161817481 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49789
02:50:17 INFO - 1499161817482 Marionette DEBUG Closed connection 355
02:50:17 INFO - 1499161817483 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49790
02:50:17 INFO - 1499161817485 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:17 INFO - 1499161817485 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:17 INFO - 1499161817488 Marionette DEBUG Register listener.js for window 12
02:50:17 INFO - 1499161817492 Marionette TRACE 356 <- [1,1,null,{"sessionId":"cee4ab30-810e-41bd-94b5-8ca01c3f4a2b","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:17 INFO - 1499161817494 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
02:50:17 INFO - 1499161817496 Marionette TRACE 356 <- [1,2,null,{}]
02:50:17 INFO - 1499161817499 Marionette DEBUG Closed connection 356
02:50:17 INFO - 1499161817499 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49791
02:50:17 INFO - 1499161817500 Marionette DEBUG Closed connection 357
02:50:17 INFO - 1499161817501 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49792
02:50:17 INFO - 1499161817502 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:17 INFO - 1499161817503 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:17 INFO - 1499161817506 Marionette DEBUG Register listener.js for window 12
02:50:17 INFO - 1499161817510 Marionette TRACE 358 <- [1,1,null,{"sessionId":"73bca377-a3fb-43d9-b92f-9cd22a1fa983","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:17 INFO - 1499161817511 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49353/clicks.html"}]
02:50:17 INFO - 1499161817514 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49353/hidden.html"
02:50:17 INFO - 1499161817520 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49353/hidden.html"
02:50:17 INFO - 1499161817521 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49353/hidden.html"
02:50:17 INFO - 1499161817544 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49353/clicks.html"
02:50:17 INFO - 1499161817555 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49353/clicks.html"
02:50:17 INFO - 1499161817559 Marionette TRACE 358 <- [1,2,null,{}]
02:50:17 INFO - 1499161817561 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
02:50:17 INFO - 1499161817573 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bcf17744-5449-4a3c-94ca-aa3ea3e82a20","ELEMENT":"bcf17744-5449-4a3c-94ca-aa3ea3e82a20"}}]
02:50:17 INFO - 1499161817574 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"bcf17744-5449-4a3c-94ca-aa3ea3e82a20"}]
02:50:17 INFO - 1499161817630 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49353/clicks.html"
02:50:17 INFO - 1499161817640 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49353/clicks.html"
02:50:17 INFO - 1499161817641 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49353/clicks.html"
02:50:17 INFO - 1499161817662 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49353/test.html"
02:50:17 INFO - 1499161817669 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49353/test.html"
02:50:17 INFO - 1499161817673 Marionette TRACE 358 <- [1,4,null,{}]
02:50:17 INFO - 1499161817674 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
02:50:17 INFO - 1499161817677 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d2f2c922-87c8-4809-ae37-5a7bd77eea52","ELEMENT":"d2f2c922-87c8-4809-ae37-5a7bd77eea52"}}]
02:50:17 INFO - 1499161817686 Marionette TRACE 358 -> [0,6,"getTitle",{}]
02:50:17 INFO - 1499161817687 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
02:50:17 INFO - 1499161817690 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}]
02:50:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499161817693 Marionette TRACE 358 <- [1,7,null,{"value":null}]
02:50:17 INFO - 1499161817695 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
02:50:17 INFO - 1499161817696 Marionette TRACE 358 <- [1,8,null,{}]
02:50:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 246ms
02:50:17 INFO - TEST-START | test_click.py TestClick.test_container_element
02:50:17 INFO - 1499161817700 Marionette DEBUG Closed connection 358
02:50:17 INFO - 1499161817701 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49795
02:50:17 INFO - 1499161817702 Marionette DEBUG Closed connection 359
02:50:17 INFO - 1499161817703 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49796
02:50:17 INFO - 1499161817704 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:17 INFO - 1499161817705 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:17 INFO - 1499161817708 Marionette DEBUG Register listener.js for window 12
02:50:17 INFO - 1499161817712 Marionette TRACE 360 <- [1,1,null,{"sessionId":"1186976b-8101-41cd-8333-6e3eda13747f","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:17 INFO - 1499161817714 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
02:50:17 INFO - 1499161817715 Marionette TRACE 360 <- [1,2,null,{}]
02:50:17 INFO - 1499161817716 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:17 INFO - 1499161817716 Marionette TRACE 360 <- [1,3,null,{}]
02:50:17 INFO - 1499161817717 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:17 INFO - 1499161817718 Marionette TRACE 360 <- [1,4,null,{}]
02:50:17 INFO - 1499161817720 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}]
02:50:17 INFO - TEST-START: test_click.py TestClick.test_container_element1499161817723 Marionette TRACE 360 <- [1,5,null,{"value":null}]
02:50:17 INFO - 1499161817724 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
02:50:17 INFO - 1499161817726 Marionette TRACE 360 <- [1,6,null,{}]
02:50:17 INFO - 1499161817728 Marionette DEBUG Closed connection 360
02:50:17 INFO - 1499161817729 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49797
02:50:17 INFO - 1499161817730 Marionette DEBUG Closed connection 361
02:50:17 INFO - 1499161817731 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49798
02:50:17 INFO - 1499161817732 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:17 INFO - 1499161817733 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:17 INFO - 1499161817736 Marionette DEBUG Register listener.js for window 12
02:50:17 INFO - 1499161817739 Marionette TRACE 362 <- [1,1,null,{"sessionId":"6cb4ea96-a65a-42f3-9c47-66d5f4741ca1","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:17 INFO - 1499161817741 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
02:50:17 INFO - 1499161817742 Marionette TRACE 362 <- [1,2,null,{}]
02:50:17 INFO - 1499161817745 Marionette DEBUG Closed connection 362
02:50:17 INFO - 1499161817745 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49799
02:50:17 INFO - 1499161817746 Marionette DEBUG Closed connection 363
02:50:17 INFO - 1499161817747 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49800
02:50:17 INFO - 1499161817748 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:17 INFO - 1499161817749 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:17 INFO - 1499161817752 Marionette DEBUG Register listener.js for window 12
02:50:17 INFO - 1499161817756 Marionette TRACE 364 <- [1,1,null,{"sessionId":"af6b0f33-c173-4869-a22e-6c0134a3971b","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:17 INFO - 1499161817758 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"}]
02:50:17 INFO - 1499161817759 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49353/test.html"
02:50:17 INFO - 1499161817762 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49353/test.html"
02:50:17 INFO - 1499161817762 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49353/test.html"
02:50:17 INFO - 1499161817789 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"
02:50:17 INFO - 1499161817795 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"
02:50:17 INFO - 1499161817807 Marionette TRACE 364 <- [1,2,null,{}]
02:50:17 INFO - 1499161817808 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:50:17 INFO - 1499161817812 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"99a4b60b-9d10-4893-bca1-dc7fbd2aecc2","ELEMENT":"99a4b60b-9d10-4893-bca1-dc7fbd2aecc2"}}]
02:50:17 INFO - 1499161817814 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"99a4b60b-9d10-4893-bca1-dc7fbd2aecc2"}]
02:50:18 INFO - 1499161818061 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:18 INFO - 1499161818062 Marionette TRACE 364 <- [1,4,null,{}]
02:50:18 INFO - 1499161818063 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"99a4b60b-9d10-4893-bca1-dc7fbd2aecc2","name":"selected"}]
02:50:18 INFO - 1499161818069 Marionette TRACE 364 <- [1,5,null,{"value":true}]
02:50:18 INFO - 1499161818070 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}]
02:50:18 INFO - TEST-END: test_click.py TestClick.test_container_element1499161818074 Marionette TRACE 364 <- [1,6,null,{"value":null}]
02:50:18 INFO - 1499161818075 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
02:50:18 INFO - 1499161818076 Marionette TRACE 364 <- [1,7,null,{}]
02:50:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 380ms
02:50:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
02:50:18 INFO - 1499161818079 Marionette DEBUG Closed connection 364
02:50:18 INFO - 1499161818080 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49801
02:50:18 INFO - 1499161818081 Marionette DEBUG Closed connection 365
02:50:18 INFO - 1499161818081 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49802
02:50:18 INFO - 1499161818082 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:18 INFO - 1499161818083 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818086 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818090 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1520ab4e-f6c8-49b5-824c-fe60c8661c54","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818092 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
02:50:18 INFO - 1499161818092 Marionette TRACE 366 <- [1,2,null,{}]
02:50:18 INFO - 1499161818093 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:18 INFO - 1499161818094 Marionette TRACE 366 <- [1,3,null,{}]
02:50:18 INFO - 1499161818095 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:18 INFO - 1499161818095 Marionette TRACE 366 <- [1,4,null,{}]
02:50:18 INFO - 1499161818099 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}]
02:50:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499161818102 Marionette TRACE 366 <- [1,5,null,{"value":null}]
02:50:18 INFO - 1499161818103 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
02:50:18 INFO - 1499161818105 Marionette TRACE 366 <- [1,6,null,{}]
02:50:18 INFO - 1499161818107 Marionette DEBUG Closed connection 366
02:50:18 INFO - 1499161818108 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49803
02:50:18 INFO - 1499161818109 Marionette DEBUG Closed connection 367
02:50:18 INFO - 1499161818109 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49804
02:50:18 INFO - 1499161818111 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:18 INFO - 1499161818111 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818114 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818118 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b5a8a72a-6d56-4820-95bd-9ed0849819c6","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818119 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
02:50:18 INFO - 1499161818121 Marionette TRACE 368 <- [1,2,null,{}]
02:50:18 INFO - 1499161818124 Marionette DEBUG Closed connection 368
02:50:18 INFO - 1499161818124 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49805
02:50:18 INFO - 1499161818125 Marionette DEBUG Closed connection 369
02:50:18 INFO - 1499161818126 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49806
02:50:18 INFO - 1499161818127 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:18 INFO - 1499161818128 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818131 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818136 Marionette TRACE 370 <- [1,1,null,{"sessionId":"cb027a18-cd45-4d02-af16-7b15357af97f","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818137 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"}]
02:50:18 INFO - 1499161818139 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"
02:50:18 INFO - 1499161818142 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"
02:50:18 INFO - 1499161818142 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"
02:50:18 INFO - 1499161818166 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"
02:50:18 INFO - 1499161818169 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"
02:50:18 INFO - 1499161818173 Marionette TRACE 370 <- [1,2,null,{}]
02:50:18 INFO - 1499161818174 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
02:50:18 INFO - 1499161818177 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9d4a9b0b-bd30-46e2-8b9d-89a448abda74","ELEMENT":"9d4a9b0b-bd30-46e2-8b9d-89a448abda74"}}]
02:50:18 INFO - 1499161818185 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9d4a9b0b-bd30-46e2-8b9d-89a448abda74"}]
02:50:18 INFO - 1499161818440 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:18 INFO - 1499161818441 Marionette TRACE 370 <- [1,4,null,{}]
02:50:18 INFO - 1499161818442 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9d4a9b0b-bd30-46e2-8b9d-89a448abda74","name":"selected"}]
02:50:18 INFO - 1499161818447 Marionette TRACE 370 <- [1,5,null,{"value":true}]
02:50:18 INFO - 1499161818449 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}]
02:50:18 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499161818452 Marionette TRACE 370 <- [1,6,null,{"value":null}]
02:50:18 INFO - 1499161818453 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
02:50:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 378ms
02:50:18 INFO - 1499161818455 Marionette TRACE 370 <- [1,7,null,{}]
02:50:18 INFO - TEST-START | test_click.py TestClick.test_css_transforms
02:50:18 INFO - 1499161818457 Marionette DEBUG Closed connection 370
02:50:18 INFO - 1499161818458 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49807
02:50:18 INFO - 1499161818459 Marionette DEBUG Closed connection 371
02:50:18 INFO - 1499161818460 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49808
02:50:18 INFO - 1499161818461 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:18 INFO - 1499161818462 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818465 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818469 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c1081256-4357-4a20-bf84-8fbdfe9b8a60","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818470 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
02:50:18 INFO - 1499161818471 Marionette TRACE 372 <- [1,2,null,{}]
02:50:18 INFO - 1499161818472 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:18 INFO - 1499161818472 Marionette TRACE 372 <- [1,3,null,{}]
02:50:18 INFO - 1499161818473 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:18 INFO - 1499161818474 Marionette TRACE 372 <- [1,4,null,{}]
02:50:18 INFO - 1499161818476 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}]
02:50:18 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499161818479 Marionette TRACE 372 <- [1,5,null,{"value":null}]
02:50:18 INFO - 1499161818481 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
02:50:18 INFO - 1499161818482 Marionette TRACE 372 <- [1,6,null,{}]
02:50:18 INFO - 1499161818485 Marionette DEBUG Closed connection 372
02:50:18 INFO - 1499161818486 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49809
02:50:18 INFO - 1499161818487 Marionette DEBUG Closed connection 373
02:50:18 INFO - 1499161818488 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49810
02:50:18 INFO - 1499161818489 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:18 INFO - 1499161818490 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818493 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818497 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d32c5362-a8ee-4feb-9bd8-96613616ba7c","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818498 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
02:50:18 INFO - 1499161818499 Marionette TRACE 374 <- [1,2,null,{}]
02:50:18 INFO - 1499161818502 Marionette DEBUG Closed connection 374
02:50:18 INFO - 1499161818503 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49811
02:50:18 INFO - 1499161818504 Marionette DEBUG Closed connection 375
02:50:18 INFO - 1499161818504 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49812
02:50:18 INFO - 1499161818505 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:18 INFO - 1499161818506 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818509 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818513 Marionette TRACE 376 <- [1,1,null,{"sessionId":"ab528da6-28f3-404a-ba21-53bd53e024d6","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818515 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"}]
02:50:18 INFO - 1499161818517 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"
02:50:18 INFO - 1499161818520 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"
02:50:18 INFO - 1499161818520 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"
02:50:18 INFO - 1499161818544 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"
02:50:18 INFO - 1499161818548 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"
02:50:18 INFO - 1499161818559 Marionette TRACE 376 <- [1,2,null,{}]
02:50:18 INFO - 1499161818561 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
02:50:18 INFO - 1499161818565 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e7c537f6-9490-4dd1-89be-940ff01f8810","ELEMENT":"e7c537f6-9490-4dd1-89be-940ff01f8810"}}]
02:50:18 INFO - 1499161818567 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"e7c537f6-9490-4dd1-89be-940ff01f8810"}]
02:50:18 INFO - 1499161818810 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:18 INFO - 1499161818811 Marionette TRACE 376 <- [1,4,null,{}]
02:50:18 INFO - 1499161818813 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}]
02:50:18 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499161818816 Marionette TRACE 376 <- [1,5,null,{"value":null}]
02:50:18 INFO - 1499161818817 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
02:50:18 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 363ms
02:50:18 INFO - 1499161818819 Marionette TRACE 376 <- [1,6,null,{}]
02:50:18 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
02:50:18 INFO - 1499161818821 Marionette DEBUG Closed connection 376
02:50:18 INFO - 1499161818823 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49813
02:50:18 INFO - 1499161818823 Marionette DEBUG Closed connection 377
02:50:18 INFO - 1499161818824 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49814
02:50:18 INFO - 1499161818825 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:18 INFO - 1499161818826 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818829 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818833 Marionette TRACE 378 <- [1,1,null,{"sessionId":"371f97f8-7353-496e-89ba-762fdcf125b5","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818834 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
02:50:18 INFO - 1499161818835 Marionette TRACE 378 <- [1,2,null,{}]
02:50:18 INFO - 1499161818836 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:18 INFO - 1499161818836 Marionette TRACE 378 <- [1,3,null,{}]
02:50:18 INFO - 1499161818837 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:18 INFO - 1499161818838 Marionette TRACE 378 <- [1,4,null,{}]
02:50:18 INFO - 1499161818840 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}]
02:50:18 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499161818843 Marionette TRACE 378 <- [1,5,null,{"value":null}]
02:50:18 INFO - 1499161818844 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
02:50:18 INFO - 1499161818846 Marionette TRACE 378 <- [1,6,null,{}]
02:50:18 INFO - 1499161818848 Marionette DEBUG Closed connection 378
02:50:18 INFO - 1499161818849 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49815
02:50:18 INFO - 1499161818850 Marionette DEBUG Closed connection 379
02:50:18 INFO - 1499161818851 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49816
02:50:18 INFO - 1499161818852 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:18 INFO - 1499161818852 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818855 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818859 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0df6f47a-e620-4cee-b927-b91343c5bb28","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818861 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
02:50:18 INFO - 1499161818862 Marionette TRACE 380 <- [1,2,null,{}]
02:50:18 INFO - 1499161818865 Marionette DEBUG Closed connection 380
02:50:18 INFO - 1499161818866 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49817
02:50:18 INFO - 1499161818867 Marionette DEBUG Closed connection 381
02:50:18 INFO - 1499161818867 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49818
02:50:18 INFO - 1499161818869 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:18 INFO - 1499161818869 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:18 INFO - 1499161818872 Marionette DEBUG Register listener.js for window 12
02:50:18 INFO - 1499161818876 Marionette TRACE 382 <- [1,1,null,{"sessionId":"dd6518f0-386e-4390-a71e-72ff271c4178","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:18 INFO - 1499161818878 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"}]
02:50:18 INFO - 1499161818880 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"
02:50:18 INFO - 1499161818882 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"
02:50:18 INFO - 1499161818883 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"
02:50:18 INFO - 1499161818905 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"
02:50:18 INFO - 1499161818907 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"
02:50:18 INFO - 1499161818912 Marionette TRACE 382 <- [1,2,null,{}]
02:50:18 INFO - 1499161818921 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
02:50:18 INFO - 1499161818924 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c5680126-12c1-4dc1-a11e-288c5371d7b8","ELEMENT":"c5680126-12c1-4dc1-a11e-288c5371d7b8"}}]
02:50:18 INFO - 1499161818925 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c5680126-12c1-4dc1-a11e-288c5371d7b8"}]
02:50:19 INFO - 1499161819180 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:19 INFO - 1499161819181 Marionette TRACE 382 <- [1,4,null,{}]
02:50:19 INFO - 1499161819183 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}]
02:50:19 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499161819186 Marionette TRACE 382 <- [1,5,null,{"value":null}]
02:50:19 INFO - 1499161819187 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
02:50:19 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 369ms
02:50:19 INFO - TEST-START | test_click.py TestClick.test_input_file
02:50:19 INFO - 1499161819189 Marionette TRACE 382 <- [1,6,null,{}]
02:50:19 INFO - 1499161819191 Marionette DEBUG Closed connection 382
02:50:19 INFO - 1499161819192 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49819
02:50:19 INFO - 1499161819193 Marionette DEBUG Closed connection 383
02:50:19 INFO - 1499161819194 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49820
02:50:19 INFO - 1499161819195 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:19 INFO - 1499161819196 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819199 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819203 Marionette TRACE 384 <- [1,1,null,{"sessionId":"9a1a4406-7ae1-475c-8ba7-aa13f285afab","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819204 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
02:50:19 INFO - 1499161819205 Marionette TRACE 384 <- [1,2,null,{}]
02:50:19 INFO - 1499161819206 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:19 INFO - 1499161819206 Marionette TRACE 384 <- [1,3,null,{}]
02:50:19 INFO - 1499161819207 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:19 INFO - 1499161819208 Marionette TRACE 384 <- [1,4,null,{}]
02:50:19 INFO - 1499161819210 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}]
02:50:19 INFO - TEST-START: test_click.py TestClick.test_input_file1499161819213 Marionette TRACE 384 <- [1,5,null,{"value":null}]
02:50:19 INFO - 1499161819214 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
02:50:19 INFO - 1499161819215 Marionette TRACE 384 <- [1,6,null,{}]
02:50:19 INFO - 1499161819218 Marionette DEBUG Closed connection 384
02:50:19 INFO - 1499161819219 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49821
02:50:19 INFO - 1499161819219 Marionette DEBUG Closed connection 385
02:50:19 INFO - 1499161819220 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49822
02:50:19 INFO - 1499161819221 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:19 INFO - 1499161819222 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819225 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819229 Marionette TRACE 386 <- [1,1,null,{"sessionId":"56d64ea3-dfdb-45c5-b820-d82e73e4c59c","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819230 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
02:50:19 INFO - 1499161819232 Marionette TRACE 386 <- [1,2,null,{}]
02:50:19 INFO - 1499161819234 Marionette DEBUG Closed connection 386
02:50:19 INFO - 1499161819235 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49823
02:50:19 INFO - 1499161819236 Marionette DEBUG Closed connection 387
02:50:19 INFO - 1499161819237 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49824
02:50:19 INFO - 1499161819238 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:19 INFO - 1499161819238 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819241 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819245 Marionette TRACE 388 <- [1,1,null,{"sessionId":"72debebe-b4c6-4432-845d-2e0ef8d7bc6d","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819247 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
02:50:19 INFO - 1499161819248 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"
02:50:19 INFO - 1499161819251 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"
02:50:19 INFO - 1499161819252 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"
02:50:19 INFO - 1499161819274 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:19 INFO - 1499161819279 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:19 INFO - 1499161819291 Marionette TRACE 388 <- [1,2,null,{}]
02:50:19 INFO - 1499161819292 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
02:50:19 INFO - 1499161819296 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b465a30a-2ef5-4dc7-89d7-7d45cf58e86e","ELEMENT":"b465a30a-2ef5-4dc7-89d7-7d45cf58e86e"}}]
02:50:19 INFO - 1499161819297 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"b465a30a-2ef5-4dc7-89d7-7d45cf58e86e"}]
02:50:19 INFO - 1499161819327 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
02:50:19 INFO - 1499161819329 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}]
02:50:19 INFO - TEST-END: test_click.py TestClick.test_input_file1499161819332 Marionette TRACE 388 <- [1,5,null,{"value":null}]
02:50:19 INFO - 1499161819333 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
02:50:19 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 146ms
02:50:19 INFO - 1499161819335 Marionette TRACE 388 <- [1,6,null,{}]
02:50:19 INFO - TEST-START | test_click.py TestClick.test_obscured_element
02:50:19 INFO - 1499161819337 Marionette DEBUG Closed connection 388
02:50:19 INFO - 1499161819339 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49825
02:50:19 INFO - 1499161819340 Marionette DEBUG Closed connection 389
02:50:19 INFO - 1499161819340 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49826
02:50:19 INFO - 1499161819341 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:19 INFO - 1499161819342 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819345 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819349 Marionette TRACE 390 <- [1,1,null,{"sessionId":"91b7dd8c-10d2-4044-bc3d-ac3b7f57be01","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819350 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
02:50:19 INFO - 1499161819351 Marionette TRACE 390 <- [1,2,null,{}]
02:50:19 INFO - 1499161819352 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:19 INFO - 1499161819353 Marionette TRACE 390 <- [1,3,null,{}]
02:50:19 INFO - 1499161819353 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:19 INFO - 1499161819354 Marionette TRACE 390 <- [1,4,null,{}]
02:50:19 INFO - 1499161819356 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}]
02:50:19 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499161819359 Marionette TRACE 390 <- [1,5,null,{"value":null}]
02:50:19 INFO - 1499161819360 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
02:50:19 INFO - 1499161819362 Marionette TRACE 390 <- [1,6,null,{}]
02:50:19 INFO - 1499161819365 Marionette DEBUG Closed connection 390
02:50:19 INFO - 1499161819366 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49827
02:50:19 INFO - 1499161819367 Marionette DEBUG Closed connection 391
02:50:19 INFO - 1499161819368 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49828
02:50:19 INFO - 1499161819369 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:19 INFO - 1499161819370 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819373 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819377 Marionette TRACE 392 <- [1,1,null,{"sessionId":"fd8e836e-0933-43bb-a3b2-f860d68575c5","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819378 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
02:50:19 INFO - 1499161819380 Marionette TRACE 392 <- [1,2,null,{}]
02:50:19 INFO - 1499161819383 Marionette DEBUG Closed connection 392
02:50:19 INFO - 1499161819383 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49829
02:50:19 INFO - 1499161819384 Marionette DEBUG Closed connection 393
02:50:19 INFO - 1499161819385 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49830
02:50:19 INFO - 1499161819387 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:19 INFO - 1499161819387 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819390 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819394 Marionette TRACE 394 <- [1,1,null,{"sessionId":"7ecaddb3-5038-4e56-8f1b-4fae0813d200","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819396 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"}]
02:50:19 INFO - 1499161819397 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:19 INFO - 1499161819400 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:19 INFO - 1499161819400 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
02:50:19 INFO - 1499161819425 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"
02:50:19 INFO - 1499161819428 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"
02:50:19 INFO - 1499161819438 Marionette TRACE 394 <- [1,2,null,{}]
02:50:19 INFO - 1499161819440 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
02:50:19 INFO - 1499161819443 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1374a899-5278-4190-a7d1-3626a39618ce","ELEMENT":"1374a899-5278-4190-a7d1-3626a39618ce"}}]
02:50:19 INFO - 1499161819444 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
02:50:19 INFO - 1499161819447 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"89cbdd71-3b3b-469a-924d-0dca644d0c42","ELEMENT":"89cbdd71-3b3b-469a-924d-0dca644d0c42"}}]
02:50:19 INFO - 1499161819448 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"1374a899-5278-4190-a7d1-3626a39618ce"}]
02:50:19 INFO - 1499161819700 Marionette DEBUG Canceled page load listener because no navigation has been detected
02:50:19 INFO - 1499161819701 Marionette TRACE 394 <- [1,5,null,{}]
02:50:19 INFO - 1499161819702 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"89cbdd71-3b3b-469a-924d-0dca644d0c42"}]
02:50:19 INFO - 1499161819721 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
02:50:19 INFO - 1499161819723 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
02:50:19 INFO - 1499161819726 Marionette TRACE 394 <- [1,7,null,{"value":false}]
02:50:19 INFO - 1499161819728 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}]
02:50:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499161819732 Marionette TRACE 394 <- [1,8,null,{"value":null}]
02:50:19 INFO - 1499161819733 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
02:50:19 INFO - 1499161819734 Marionette TRACE 394 <- [1,9,null,{}]
02:50:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 399ms
02:50:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
02:50:19 INFO - 1499161819737 Marionette DEBUG Closed connection 394
02:50:19 INFO - 1499161819738 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49831
02:50:19 INFO - 1499161819739 Marionette DEBUG Closed connection 395
02:50:19 INFO - 1499161819739 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49832
02:50:19 INFO - 1499161819740 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:19 INFO - 1499161819741 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819744 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819748 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ad2d9eb7-9af3-4bf7-8f28-7c225ed653d8","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819750 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
02:50:19 INFO - 1499161819750 Marionette TRACE 396 <- [1,2,null,{}]
02:50:19 INFO - 1499161819751 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
02:50:19 INFO - 1499161819752 Marionette TRACE 396 <- [1,3,null,{}]
02:50:19 INFO - 1499161819753 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
02:50:19 INFO - 1499161819753 Marionette TRACE 396 <- [1,4,null,{}]
02:50:19 INFO - 1499161819755 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}]
02:50:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499161819758 Marionette TRACE 396 <- [1,5,null,{"value":null}]
02:50:19 INFO - 1499161819759 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
02:50:19 INFO - 1499161819761 Marionette TRACE 396 <- [1,6,null,{}]
02:50:19 INFO - 1499161819763 Marionette DEBUG Closed connection 396
02:50:19 INFO - 1499161819764 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49833
02:50:19 INFO - 1499161819765 Marionette DEBUG Closed connection 397
02:50:19 INFO - 1499161819766 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49834
02:50:19 INFO - 1499161819767 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
02:50:19 INFO - 1499161819767 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819770 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819774 Marionette TRACE 398 <- [1,1,null,{"sessionId":"875ae81c-dec8-432b-81a6-b44ec5dbabaa","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819775 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
02:50:19 INFO - 1499161819776 Marionette TRACE 398 <- [1,2,null,{}]
02:50:19 INFO - 1499161819779 Marionette DEBUG Closed connection 398
02:50:19 INFO - 1499161819780 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49835
02:50:19 INFO - 1499161819780 Marionette DEBUG Closed connection 399
02:50:19 INFO - 1499161819781 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49836
02:50:19 INFO - 1499161819783 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
02:50:19 INFO - 1499161819784 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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}
02:50:19 INFO - 1499161819787 Marionette DEBUG Register listener.js for window 12
02:50:19 INFO - 1499161819791 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4b029e6e-8f93-433c-9ace-03411ffa2845","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":4012,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpqyfyx0.mozrunner","moz:accessibilityChecks":false}}]
02:50:19 INFO - 1499161819792 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"}]
02:50:19 INFO - 1499161819794 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"
02:50:19 INFO - 1499161819797 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"
02:50:19 INFO - 1499161819797 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"
02:50:19 INFO - 1499161819820 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"
02:50:19 INFO - 1499161819823 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"
02:50:19 INFO - 1499161819827 Marionette TRACE 400 <- [1,2,null,{}]
02:50:19 INFO - 1499161819835 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
02:50:19 INFO - 1499161819839 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"af270df6-cc43-41cc-8792-29df517c04f7","ELEMENT":"af270df6-cc43-41cc-8792-29df517c04f7"}}]
02:50:19 INFO - 1499161819840 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"af270df6-cc43-41cc-8792-29df517c04f7"}]
02:50:19 INFO - 1499161819865 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
02:50:19 INFO - 1499161819867 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"af270df6-cc43-41cc-8792-29df517c04f7"}]
02:50:19 INFO - 1499161819890 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element