could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
16:35:02 INFO - 1499729702491 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}]
16:35:02 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499729702495 Marionette TRACE 352 <- [1,5,null,{"value":null}]
16:35:02 INFO - 1499729702496 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
16:35:02 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 148ms
16:35:02 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
16:35:02 INFO - 1499729702497 Marionette TRACE 352 <- [1,6,null,{}]
16:35:02 INFO - 1499729702499 Marionette DEBUG Closed connection 352
16:35:02 INFO - 1499729702500 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50849
16:35:02 INFO - 1499729702501 Marionette DEBUG Closed connection 353
16:35:02 INFO - 1499729702503 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50850
16:35:02 INFO - 1499729702504 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:02 INFO - 1499729702505 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:02 INFO - 1499729702508 Marionette DEBUG Register listener.js for window 4294967297
16:35:02 INFO - 1499729702512 Marionette TRACE 354 <- [1,1,null,{"sessionId":"c438b88a-6822-4f59-bcaf-950830482c7d","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:02 INFO - 1499729702513 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
16:35:02 INFO - 1499729702514 Marionette TRACE 354 <- [1,2,null,{}]
16:35:02 INFO - 1499729702515 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:35:02 INFO - 1499729702515 Marionette TRACE 354 <- [1,3,null,{}]
16:35:02 INFO - 1499729702516 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
16:35:02 INFO - 1499729702517 Marionette TRACE 354 <- [1,4,null,{}]
16:35:02 INFO - 1499729702519 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}]
16:35:02 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499729702522 Marionette TRACE 354 <- [1,5,null,{"value":null}]
16:35:02 INFO - 1499729702523 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
16:35:02 INFO - 1499729702524 Marionette TRACE 354 <- [1,6,null,{}]
16:35:02 INFO - 1499729702526 Marionette DEBUG Closed connection 354
16:35:02 INFO - 1499729702527 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50851
16:35:02 INFO - 1499729702527 Marionette DEBUG Closed connection 355
16:35:02 INFO - 1499729702528 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50852
16:35:02 INFO - 1499729702529 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:02 INFO - 1499729702530 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:02 INFO - 1499729702532 Marionette DEBUG Register listener.js for window 4294967297
16:35:02 INFO - 1499729702536 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f5299535-bd4c-48a2-9973-762b5b784268","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:02 INFO - 1499729702537 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
16:35:02 INFO - 1499729702539 Marionette TRACE 356 <- [1,2,null,{}]
16:35:02 INFO - 1499729702540 Marionette DEBUG Closed connection 356
16:35:02 INFO - 1499729702541 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50853
16:35:02 INFO - 1499729702542 Marionette DEBUG Closed connection 357
16:35:02 INFO - 1499729702543 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50854
16:35:02 INFO - 1499729702544 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:35:02 INFO - 1499729702544 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:02 INFO - 1499729702547 Marionette DEBUG Register listener.js for window 4294967297
16:35:02 INFO - 1499729702550 Marionette TRACE 358 <- [1,1,null,{"sessionId":"5cd3c3e8-8560-47f0-8802-8ac99d1577f5","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:02 INFO - 1499729702552 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:50402/clicks.html"}]
16:35:02 INFO - 1499729702554 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50402/hidden.html"
16:35:02 INFO - 1499729702564 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50402/hidden.html"
16:35:02 INFO - 1499729702564 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50402/hidden.html"
16:35:02 INFO - 1499729702572 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50402/clicks.html"
16:35:02 INFO - 1499729702578 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50402/clicks.html"
16:35:02 INFO - 1499729702591 Marionette TRACE 358 <- [1,2,null,{}]
16:35:02 INFO - 1499729702599 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
16:35:02 INFO - 1499729702602 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a0d0e7d-21af-482a-ad1a-25dd54d4a51b","ELEMENT":"5a0d0e7d-21af-482a-ad1a-25dd54d4a51b"}}]
16:35:02 INFO - 1499729702603 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5a0d0e7d-21af-482a-ad1a-25dd54d4a51b"}]
16:35:02 INFO - 1499729702647 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50402/clicks.html"
16:35:02 INFO - 1499729702661 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50402/clicks.html"
16:35:02 INFO - 1499729702661 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50402/clicks.html"
16:35:02 INFO - 1499729702674 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50402/test.html"
16:35:02 INFO - 1499729702678 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50402/test.html"
16:35:02 INFO - 1499729702680 Marionette TRACE 358 <- [1,4,null,{}]
16:35:02 INFO - 1499729702689 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
16:35:02 INFO - 1499729702695 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"212b3740-ec61-4db1-8971-6b0ade4fd6d5","ELEMENT":"212b3740-ec61-4db1-8971-6b0ade4fd6d5"}}]
16:35:02 INFO - 1499729702696 Marionette TRACE 358 -> [0,6,"getTitle",{}]
16:35:02 INFO - 1499729702697 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
16:35:02 INFO - 1499729702699 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}]
16:35:02 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499729702702 Marionette TRACE 358 <- [1,7,null,{"value":null}]
16:35:02 INFO - 1499729702703 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
16:35:02 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 207ms
16:35:02 INFO - TEST-START | test_click.py TestClick.test_container_element
16:35:02 INFO - 1499729702705 Marionette TRACE 358 <- [1,8,null,{}]
16:35:02 INFO - 1499729702707 Marionette DEBUG Closed connection 358
16:35:02 INFO - 1499729702708 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50857
16:35:02 INFO - 1499729702709 Marionette DEBUG Closed connection 359
16:35:02 INFO - 1499729702710 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50858
16:35:02 INFO - 1499729702711 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:02 INFO - 1499729702712 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:02 INFO - 1499729702715 Marionette DEBUG Register listener.js for window 4294967297
16:35:02 INFO - 1499729702718 Marionette TRACE 360 <- [1,1,null,{"sessionId":"c628c110-a045-4060-a58e-45e30111c60a","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:02 INFO - 1499729702720 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
16:35:02 INFO - 1499729702720 Marionette TRACE 360 <- [1,2,null,{}]
16:35:02 INFO - 1499729702721 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:35:02 INFO - 1499729702722 Marionette TRACE 360 <- [1,3,null,{}]
16:35:02 INFO - 1499729702723 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
16:35:02 INFO - 1499729702723 Marionette TRACE 360 <- [1,4,null,{}]
16:35:02 INFO - 1499729702725 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}]
16:35:02 INFO - TEST-START: test_click.py TestClick.test_container_element1499729702728 Marionette TRACE 360 <- [1,5,null,{"value":null}]
16:35:02 INFO - 1499729702729 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
16:35:02 INFO - 1499729702731 Marionette TRACE 360 <- [1,6,null,{}]
16:35:02 INFO - 1499729702733 Marionette DEBUG Closed connection 360
16:35:02 INFO - 1499729702733 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50859
16:35:02 INFO - 1499729702734 Marionette DEBUG Closed connection 361
16:35:02 INFO - 1499729702735 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50860
16:35:02 INFO - 1499729702736 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:02 INFO - 1499729702737 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:02 INFO - 1499729702740 Marionette DEBUG Register listener.js for window 4294967297
16:35:02 INFO - 1499729702743 Marionette TRACE 362 <- [1,1,null,{"sessionId":"18e44688-5b9e-4fbc-a9de-5f127d26526b","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:02 INFO - 1499729702745 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
16:35:02 INFO - 1499729702746 Marionette TRACE 362 <- [1,2,null,{}]
16:35:02 INFO - 1499729702748 Marionette DEBUG Closed connection 362
16:35:02 INFO - 1499729702749 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50861
16:35:02 INFO - 1499729702749 Marionette DEBUG Closed connection 363
16:35:02 INFO - 1499729702750 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50862
16:35:02 INFO - 1499729702751 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:35:02 INFO - 1499729702752 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:02 INFO - 1499729702755 Marionette DEBUG Register listener.js for window 4294967297
16:35:02 INFO - 1499729702758 Marionette TRACE 364 <- [1,1,null,{"sessionId":"b36e5e04-569c-4129-8228-076d6c897519","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:02 INFO - 1499729702760 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"}]
16:35:02 INFO - 1499729702762 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50402/test.html"
16:35:02 INFO - 1499729702764 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50402/test.html"
16:35:02 INFO - 1499729702765 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50402/test.html"
16:35:02 INFO - 1499729702772 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"
16:35:02 INFO - 1499729702776 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"
16:35:02 INFO - 1499729702784 Marionette TRACE 364 <- [1,2,null,{}]
16:35:02 INFO - 1499729702797 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
16:35:02 INFO - 1499729702800 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"68c17a1f-d611-4ac3-a021-ff7f5982cfa4","ELEMENT":"68c17a1f-d611-4ac3-a021-ff7f5982cfa4"}}]
16:35:02 INFO - 1499729702806 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"68c17a1f-d611-4ac3-a021-ff7f5982cfa4"}]
16:35:03 INFO - 1499729703051 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:35:03 INFO - 1499729703053 Marionette TRACE 364 <- [1,4,null,{}]
16:35:03 INFO - 1499729703054 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"68c17a1f-d611-4ac3-a021-ff7f5982cfa4","name":"selected"}]
16:35:03 INFO - 1499729703059 Marionette TRACE 364 <- [1,5,null,{"value":true}]
16:35:03 INFO - 1499729703061 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}]
16:35:03 INFO - TEST-END: test_click.py TestClick.test_container_element1499729703064 Marionette TRACE 364 <- [1,6,null,{"value":null}]
16:35:03 INFO - 1499729703065 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
16:35:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 362ms
16:35:03 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
16:35:03 INFO - 1499729703067 Marionette TRACE 364 <- [1,7,null,{}]
16:35:03 INFO - 1499729703069 Marionette DEBUG Closed connection 364
16:35:03 INFO - 1499729703069 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50863
16:35:03 INFO - 1499729703070 Marionette DEBUG Closed connection 365
16:35:03 INFO - 1499729703071 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50864
16:35:03 INFO - 1499729703072 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:03 INFO - 1499729703073 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703076 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703079 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b2c3a7c5-904f-4658-b1d4-2121a5d25112","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703080 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
16:35:03 INFO - 1499729703081 Marionette TRACE 366 <- [1,2,null,{}]
16:35:03 INFO - 1499729703082 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:35:03 INFO - 1499729703083 Marionette TRACE 366 <- [1,3,null,{}]
16:35:03 INFO - 1499729703084 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
16:35:03 INFO - 1499729703084 Marionette TRACE 366 <- [1,4,null,{}]
16:35:03 INFO - 1499729703086 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}]
16:35:03 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499729703089 Marionette TRACE 366 <- [1,5,null,{"value":null}]
16:35:03 INFO - 1499729703090 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
16:35:03 INFO - 1499729703092 Marionette TRACE 366 <- [1,6,null,{}]
16:35:03 INFO - 1499729703094 Marionette DEBUG Closed connection 366
16:35:03 INFO - 1499729703094 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50865
16:35:03 INFO - 1499729703095 Marionette DEBUG Closed connection 367
16:35:03 INFO - 1499729703096 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50866
16:35:03 INFO - 1499729703097 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:03 INFO - 1499729703098 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703101 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703104 Marionette TRACE 368 <- [1,1,null,{"sessionId":"9f0c4eb3-5e12-4bea-a113-4774e11f0918","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703105 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
16:35:03 INFO - 1499729703107 Marionette TRACE 368 <- [1,2,null,{}]
16:35:03 INFO - 1499729703108 Marionette DEBUG Closed connection 368
16:35:03 INFO - 1499729703109 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50867
16:35:03 INFO - 1499729703110 Marionette DEBUG Closed connection 369
16:35:03 INFO - 1499729703111 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50868
16:35:03 INFO - 1499729703112 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:35:03 INFO - 1499729703113 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703116 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703119 Marionette TRACE 370 <- [1,1,null,{"sessionId":"602ce32e-26ad-4cd3-aeda-40aa385aa06c","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703120 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"}]
16:35:03 INFO - 1499729703122 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"
16:35:03 INFO - 1499729703125 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"
16:35:03 INFO - 1499729703125 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"
16:35:03 INFO - 1499729703132 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"
16:35:03 INFO - 1499729703136 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"
16:35:03 INFO - 1499729703146 Marionette TRACE 370 <- [1,2,null,{}]
16:35:03 INFO - 1499729703150 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
16:35:03 INFO - 1499729703157 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a66684b5-74c3-4740-af6f-b2d29cd7c630","ELEMENT":"a66684b5-74c3-4740-af6f-b2d29cd7c630"}}]
16:35:03 INFO - 1499729703158 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a66684b5-74c3-4740-af6f-b2d29cd7c630"}]
16:35:03 INFO - 1499729703408 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:35:03 INFO - 1499729703409 Marionette TRACE 370 <- [1,4,null,{}]
16:35:03 INFO - 1499729703411 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a66684b5-74c3-4740-af6f-b2d29cd7c630","name":"selected"}]
16:35:03 INFO - 1499729703416 Marionette TRACE 370 <- [1,5,null,{"value":true}]
16:35:03 INFO - 1499729703418 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}]
16:35:03 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499729703421 Marionette TRACE 370 <- [1,6,null,{"value":null}]
16:35:03 INFO - 1499729703422 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
16:35:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 356ms
16:35:03 INFO - TEST-START | test_click.py TestClick.test_css_transforms
16:35:03 INFO - 1499729703423 Marionette TRACE 370 <- [1,7,null,{}]
16:35:03 INFO - 1499729703425 Marionette DEBUG Closed connection 370
16:35:03 INFO - 1499729703426 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50869
16:35:03 INFO - 1499729703427 Marionette DEBUG Closed connection 371
16:35:03 INFO - 1499729703428 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50870
16:35:03 INFO - 1499729703429 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:03 INFO - 1499729703430 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703433 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703436 Marionette TRACE 372 <- [1,1,null,{"sessionId":"dd6ac341-8583-47ba-a87e-9783a75af797","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703437 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
16:35:03 INFO - 1499729703438 Marionette TRACE 372 <- [1,2,null,{}]
16:35:03 INFO - 1499729703439 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:35:03 INFO - 1499729703439 Marionette TRACE 372 <- [1,3,null,{}]
16:35:03 INFO - 1499729703440 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
16:35:03 INFO - 1499729703441 Marionette TRACE 372 <- [1,4,null,{}]
16:35:03 INFO - 1499729703443 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}]
16:35:03 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499729703445 Marionette TRACE 372 <- [1,5,null,{"value":null}]
16:35:03 INFO - 1499729703446 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
16:35:03 INFO - 1499729703448 Marionette TRACE 372 <- [1,6,null,{}]
16:35:03 INFO - 1499729703450 Marionette DEBUG Closed connection 372
16:35:03 INFO - 1499729703451 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50871
16:35:03 INFO - 1499729703452 Marionette DEBUG Closed connection 373
16:35:03 INFO - 1499729703452 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50872
16:35:03 INFO - 1499729703453 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:03 INFO - 1499729703454 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703457 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703460 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a79a3342-3e86-4ca1-9c63-05484995800f","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703461 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
16:35:03 INFO - 1499729703463 Marionette TRACE 374 <- [1,2,null,{}]
16:35:03 INFO - 1499729703464 Marionette DEBUG Closed connection 374
16:35:03 INFO - 1499729703465 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50873
16:35:03 INFO - 1499729703466 Marionette DEBUG Closed connection 375
16:35:03 INFO - 1499729703467 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50874
16:35:03 INFO - 1499729703468 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:35:03 INFO - 1499729703468 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703471 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703482 Marionette TRACE 376 <- [1,1,null,{"sessionId":"17fc9d5f-12db-4f1d-81e8-4639eeb789e2","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703484 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"}]
16:35:03 INFO - 1499729703486 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"
16:35:03 INFO - 1499729703490 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"
16:35:03 INFO - 1499729703490 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"
16:35:03 INFO - 1499729703497 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"
16:35:03 INFO - 1499729703500 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"
16:35:03 INFO - 1499729703512 Marionette TRACE 376 <- [1,2,null,{}]
16:35:03 INFO - 1499729703516 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
16:35:03 INFO - 1499729703523 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eabbfa44-33a0-4648-b947-deba41e74606","ELEMENT":"eabbfa44-33a0-4648-b947-deba41e74606"}}]
16:35:03 INFO - 1499729703524 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"eabbfa44-33a0-4648-b947-deba41e74606"}]
16:35:03 INFO - 1499729703764 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:35:03 INFO - 1499729703765 Marionette TRACE 376 <- [1,4,null,{}]
16:35:03 INFO - 1499729703767 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}]
16:35:03 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499729703771 Marionette TRACE 376 <- [1,5,null,{"value":null}]
16:35:03 INFO - 1499729703772 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
16:35:03 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 349ms
16:35:03 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
16:35:03 INFO - 1499729703773 Marionette TRACE 376 <- [1,6,null,{}]
16:35:03 INFO - 1499729703775 Marionette DEBUG Closed connection 376
16:35:03 INFO - 1499729703776 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50875
16:35:03 INFO - 1499729703777 Marionette DEBUG Closed connection 377
16:35:03 INFO - 1499729703777 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50876
16:35:03 INFO - 1499729703779 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:03 INFO - 1499729703779 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703782 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703786 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ab1cb63d-2d94-4106-bb97-31c5fa28144d","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703787 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
16:35:03 INFO - 1499729703787 Marionette TRACE 378 <- [1,2,null,{}]
16:35:03 INFO - 1499729703788 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:35:03 INFO - 1499729703789 Marionette TRACE 378 <- [1,3,null,{}]
16:35:03 INFO - 1499729703790 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
16:35:03 INFO - 1499729703790 Marionette TRACE 378 <- [1,4,null,{}]
16:35:03 INFO - 1499729703792 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}]
16:35:03 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499729703795 Marionette TRACE 378 <- [1,5,null,{"value":null}]
16:35:03 INFO - 1499729703796 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
16:35:03 INFO - 1499729703797 Marionette TRACE 378 <- [1,6,null,{}]
16:35:03 INFO - 1499729703799 Marionette DEBUG Closed connection 378
16:35:03 INFO - 1499729703800 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50877
16:35:03 INFO - 1499729703801 Marionette DEBUG Closed connection 379
16:35:03 INFO - 1499729703801 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50878
16:35:03 INFO - 1499729703802 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:03 INFO - 1499729703803 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703806 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703809 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8d42b9d0-e6a5-4a45-832d-2bfc67d74c42","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703810 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
16:35:03 INFO - 1499729703812 Marionette TRACE 380 <- [1,2,null,{}]
16:35:03 INFO - 1499729703813 Marionette DEBUG Closed connection 380
16:35:03 INFO - 1499729703814 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50879
16:35:03 INFO - 1499729703815 Marionette DEBUG Closed connection 381
16:35:03 INFO - 1499729703816 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50880
16:35:03 INFO - 1499729703817 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:35:03 INFO - 1499729703817 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:03 INFO - 1499729703820 Marionette DEBUG Register listener.js for window 4294967297
16:35:03 INFO - 1499729703823 Marionette TRACE 382 <- [1,1,null,{"sessionId":"969dff75-8940-461f-89b9-11bd0f6ab52f","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:03 INFO - 1499729703824 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"}]
16:35:03 INFO - 1499729703826 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"
16:35:03 INFO - 1499729703829 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"
16:35:03 INFO - 1499729703829 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"
16:35:03 INFO - 1499729703838 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"
16:35:03 INFO - 1499729703840 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"
16:35:03 INFO - 1499729703848 Marionette TRACE 382 <- [1,2,null,{}]
16:35:03 INFO - 1499729703857 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
16:35:03 INFO - 1499729703863 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ff28e986-2493-4588-8cc5-15c3ae243539","ELEMENT":"ff28e986-2493-4588-8cc5-15c3ae243539"}}]
16:35:03 INFO - 1499729703864 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ff28e986-2493-4588-8cc5-15c3ae243539"}]
16:35:04 INFO - 1499729704107 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:35:04 INFO - 1499729704109 Marionette TRACE 382 <- [1,4,null,{}]
16:35:04 INFO - 1499729704111 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}]
16:35:04 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499729704114 Marionette TRACE 382 <- [1,5,null,{"value":null}]
16:35:04 INFO - 1499729704115 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
16:35:04 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 342ms
16:35:04 INFO - TEST-START | test_click.py TestClick.test_input_file
16:35:04 INFO - 1499729704116 Marionette TRACE 382 <- [1,6,null,{}]
16:35:04 INFO - 1499729704118 Marionette DEBUG Closed connection 382
16:35:04 INFO - 1499729704119 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50881
16:35:04 INFO - 1499729704120 Marionette DEBUG Closed connection 383
16:35:04 INFO - 1499729704121 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50882
16:35:04 INFO - 1499729704122 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:04 INFO - 1499729704122 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704125 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704129 Marionette TRACE 384 <- [1,1,null,{"sessionId":"73e2ae8f-94c4-4197-8382-3c54b15d185d","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704130 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
16:35:04 INFO - 1499729704131 Marionette TRACE 384 <- [1,2,null,{}]
16:35:04 INFO - 1499729704132 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:35:04 INFO - 1499729704132 Marionette TRACE 384 <- [1,3,null,{}]
16:35:04 INFO - 1499729704133 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
16:35:04 INFO - 1499729704134 Marionette TRACE 384 <- [1,4,null,{}]
16:35:04 INFO - 1499729704136 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}]
16:35:04 INFO - TEST-START: test_click.py TestClick.test_input_file1499729704138 Marionette TRACE 384 <- [1,5,null,{"value":null}]
16:35:04 INFO - 1499729704139 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
16:35:04 INFO - 1499729704141 Marionette TRACE 384 <- [1,6,null,{}]
16:35:04 INFO - 1499729704143 Marionette DEBUG Closed connection 384
16:35:04 INFO - 1499729704144 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50883
16:35:04 INFO - 1499729704144 Marionette DEBUG Closed connection 385
16:35:04 INFO - 1499729704145 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50884
16:35:04 INFO - 1499729704146 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:04 INFO - 1499729704147 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704150 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704153 Marionette TRACE 386 <- [1,1,null,{"sessionId":"29212959-1e87-455e-aa14-9680732ea4a2","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704154 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
16:35:04 INFO - 1499729704156 Marionette TRACE 386 <- [1,2,null,{}]
16:35:04 INFO - 1499729704157 Marionette DEBUG Closed connection 386
16:35:04 INFO - 1499729704158 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50885
16:35:04 INFO - 1499729704159 Marionette DEBUG Closed connection 387
16:35:04 INFO - 1499729704160 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50886
16:35:04 INFO - 1499729704161 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:35:04 INFO - 1499729704161 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704164 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704167 Marionette TRACE 388 <- [1,1,null,{"sessionId":"fed3767f-06e5-4814-ba05-6c59b2f1b442","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704170 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
16:35:04 INFO - 1499729704172 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"
16:35:04 INFO - 1499729704175 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"
16:35:04 INFO - 1499729704175 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"
16:35:04 INFO - 1499729704182 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:35:04 INFO - 1499729704188 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:35:04 INFO - 1499729704198 Marionette TRACE 388 <- [1,2,null,{}]
16:35:04 INFO - 1499729704207 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
16:35:04 INFO - 1499729704213 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5905bf0d-a238-400e-8966-9832f7de393b","ELEMENT":"5905bf0d-a238-400e-8966-9832f7de393b"}}]
16:35:04 INFO - 1499729704214 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"5905bf0d-a238-400e-8966-9832f7de393b"}]
16:35:04 INFO - 1499729704247 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
16:35:04 INFO - 1499729704249 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}]
16:35:04 INFO - TEST-END: test_click.py TestClick.test_input_file1499729704253 Marionette TRACE 388 <- [1,5,null,{"value":null}]
16:35:04 INFO - 1499729704254 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
16:35:04 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 138ms
16:35:04 INFO - TEST-START | test_click.py TestClick.test_obscured_element
16:35:04 INFO - 1499729704255 Marionette TRACE 388 <- [1,6,null,{}]
16:35:04 INFO - 1499729704257 Marionette DEBUG Closed connection 388
16:35:04 INFO - 1499729704258 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50887
16:35:04 INFO - 1499729704259 Marionette DEBUG Closed connection 389
16:35:04 INFO - 1499729704260 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50888
16:35:04 INFO - 1499729704261 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:04 INFO - 1499729704261 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704264 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704268 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fcde0694-a987-4275-871a-010c234daeba","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704269 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
16:35:04 INFO - 1499729704269 Marionette TRACE 390 <- [1,2,null,{}]
16:35:04 INFO - 1499729704270 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:35:04 INFO - 1499729704271 Marionette TRACE 390 <- [1,3,null,{}]
16:35:04 INFO - 1499729704272 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
16:35:04 INFO - 1499729704273 Marionette TRACE 390 <- [1,4,null,{}]
16:35:04 INFO - 1499729704274 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}]
16:35:04 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499729704277 Marionette TRACE 390 <- [1,5,null,{"value":null}]
16:35:04 INFO - 1499729704278 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
16:35:04 INFO - 1499729704280 Marionette TRACE 390 <- [1,6,null,{}]
16:35:04 INFO - 1499729704281 Marionette DEBUG Closed connection 390
16:35:04 INFO - 1499729704282 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50889
16:35:04 INFO - 1499729704283 Marionette DEBUG Closed connection 391
16:35:04 INFO - 1499729704284 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50890
16:35:04 INFO - 1499729704285 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:04 INFO - 1499729704286 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704288 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704291 Marionette TRACE 392 <- [1,1,null,{"sessionId":"1cc3d290-78a8-41e4-a8af-dbe1b783aa5f","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704293 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
16:35:04 INFO - 1499729704294 Marionette TRACE 392 <- [1,2,null,{}]
16:35:04 INFO - 1499729704296 Marionette DEBUG Closed connection 392
16:35:04 INFO - 1499729704297 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50891
16:35:04 INFO - 1499729704298 Marionette DEBUG Closed connection 393
16:35:04 INFO - 1499729704298 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50892
16:35:04 INFO - 1499729704299 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:35:04 INFO - 1499729704300 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704303 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704306 Marionette TRACE 394 <- [1,1,null,{"sessionId":"4c2ba995-bab2-415b-b9d9-3e35a54ff73d","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704307 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"}]
16:35:04 INFO - 1499729704309 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:35:04 INFO - 1499729704312 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:35:04 INFO - 1499729704312 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:35:04 INFO - 1499729704321 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"
16:35:04 INFO - 1499729704323 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"
16:35:04 INFO - 1499729704330 Marionette TRACE 394 <- [1,2,null,{}]
16:35:04 INFO - 1499729704340 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
16:35:04 INFO - 1499729704343 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5e5c3b4b-e133-4b5a-8541-61ff2775c412","ELEMENT":"5e5c3b4b-e133-4b5a-8541-61ff2775c412"}}]
16:35:04 INFO - 1499729704344 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
16:35:04 INFO - 1499729704346 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f71b21e3-9d8b-4724-ae85-b6e9ed21a5b1","ELEMENT":"f71b21e3-9d8b-4724-ae85-b6e9ed21a5b1"}}]
16:35:04 INFO - 1499729704347 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"5e5c3b4b-e133-4b5a-8541-61ff2775c412"}]
16:35:04 INFO - 1499729704587 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:35:04 INFO - 1499729704588 Marionette TRACE 394 <- [1,5,null,{}]
16:35:04 INFO - 1499729704590 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"f71b21e3-9d8b-4724-ae85-b6e9ed21a5b1"}]
16:35:04 INFO - 1499729704609 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
16:35:04 INFO - 1499729704611 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
16:35:04 INFO - 1499729704614 Marionette TRACE 394 <- [1,7,null,{"value":false}]
16:35:04 INFO - 1499729704615 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}]
16:35:04 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499729704618 Marionette TRACE 394 <- [1,8,null,{"value":null}]
16:35:04 INFO - 1499729704620 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
16:35:04 INFO - 1499729704621 Marionette TRACE 394 <- [1,9,null,{}]
16:35:04 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 365ms
16:35:04 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
16:35:04 INFO - 1499729704623 Marionette DEBUG Closed connection 394
16:35:04 INFO - 1499729704624 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50893
16:35:04 INFO - 1499729704625 Marionette DEBUG Closed connection 395
16:35:04 INFO - 1499729704625 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50894
16:35:04 INFO - 1499729704626 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:04 INFO - 1499729704627 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704630 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704634 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9782ae35-970c-4e0d-ac5d-6f7ae9208619","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704635 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
16:35:04 INFO - 1499729704636 Marionette TRACE 396 <- [1,2,null,{}]
16:35:04 INFO - 1499729704637 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:35:04 INFO - 1499729704637 Marionette TRACE 396 <- [1,3,null,{}]
16:35:04 INFO - 1499729704638 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
16:35:04 INFO - 1499729704639 Marionette TRACE 396 <- [1,4,null,{}]
16:35:04 INFO - 1499729704640 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}]
16:35:04 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499729704643 Marionette TRACE 396 <- [1,5,null,{"value":null}]
16:35:04 INFO - 1499729704644 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
16:35:04 INFO - 1499729704646 Marionette TRACE 396 <- [1,6,null,{}]
16:35:04 INFO - 1499729704648 Marionette DEBUG Closed connection 396
16:35:04 INFO - 1499729704648 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50895
16:35:04 INFO - 1499729704649 Marionette DEBUG Closed connection 397
16:35:04 INFO - 1499729704650 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50896
16:35:04 INFO - 1499729704651 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:35:04 INFO - 1499729704652 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704655 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704658 Marionette TRACE 398 <- [1,1,null,{"sessionId":"c65bebbf-3c8a-4f1c-9a6f-fffc83c326f1","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704659 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
16:35:04 INFO - 1499729704661 Marionette TRACE 398 <- [1,2,null,{}]
16:35:04 INFO - 1499729704662 Marionette DEBUG Closed connection 398
16:35:04 INFO - 1499729704663 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50897
16:35:04 INFO - 1499729704664 Marionette DEBUG Closed connection 399
16:35:04 INFO - 1499729704665 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50898
16:35:04 INFO - 1499729704666 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:35:04 INFO - 1499729704666 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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}
16:35:04 INFO - 1499729704669 Marionette DEBUG Register listener.js for window 4294967297
16:35:04 INFO - 1499729704673 Marionette TRACE 400 <- [1,1,null,{"sessionId":"64947316-bfc8-4f5b-978d-14e228b3263c","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":5848,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb81gob.mozrunner","moz:accessibilityChecks":false}}]
16:35:04 INFO - 1499729704674 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"}]
16:35:04 INFO - 1499729704676 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"
16:35:04 INFO - 1499729704679 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"
16:35:04 INFO - 1499729704679 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"
16:35:04 INFO - 1499729704688 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"
16:35:04 INFO - 1499729704690 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"
16:35:04 INFO - 1499729704699 Marionette TRACE 400 <- [1,2,null,{}]
16:35:04 INFO - 1499729704707 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
16:35:04 INFO - 1499729704712 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0897bbaf-be1f-4d13-8417-ea5cca3abd55","ELEMENT":"0897bbaf-be1f-4d13-8417-ea5cca3abd55"}}]
16:35:04 INFO - 1499729704714 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"0897bbaf-be1f-4d13-8417-ea5cca3abd55"}]
16:35:04 INFO - 1499729704736 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
16:35:04 INFO - 1499729704737 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"0897bbaf-be1f-4d13-8417-ea5cca3abd55"}]
16:35:04 INFO - 1499729704756 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element