could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:53:53 INFO - 1500314033461 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
10:53:53 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500314033464 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:53:53 INFO - 1500314033465 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:53:53 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 157ms
10:53:53 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:53:53 INFO - 1500314033467 Marionette TRACE 352 <- [1,6,null,{}]
10:53:53 INFO - 1500314033469 Marionette DEBUG Closed connection 352
10:53:53 INFO - 1500314033470 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50087
10:53:53 INFO - 1500314033472 Marionette DEBUG Closed connection 353
10:53:53 INFO - 1500314033472 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50088
10:53:53 INFO - 1500314033473 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:53 INFO - 1500314033474 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:53 INFO - 1500314033477 Marionette DEBUG Register listener.js for window 15
10:53:53 INFO - 1500314033481 Marionette TRACE 354 <- [1,1,null,{"sessionId":"8d530545-2d95-4e14-94fe-306d23b7860e","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:53 INFO - 1500314033483 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:53:53 INFO - 1500314033483 Marionette TRACE 354 <- [1,2,null,{}]
10:53:53 INFO - 1500314033484 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:53:53 INFO - 1500314033485 Marionette TRACE 354 <- [1,3,null,{}]
10:53:53 INFO - 1500314033486 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:53:53 INFO - 1500314033486 Marionette TRACE 354 <- [1,4,null,{}]
10:53:53 INFO - 1500314033488 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
10:53:53 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500314033492 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:53:53 INFO - 1500314033493 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:53:53 INFO - 1500314033495 Marionette TRACE 354 <- [1,6,null,{}]
10:53:53 INFO - 1500314033498 Marionette DEBUG Closed connection 354
10:53:53 INFO - 1500314033498 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50089
10:53:53 INFO - 1500314033499 Marionette DEBUG Closed connection 355
10:53:53 INFO - 1500314033500 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50090
10:53:53 INFO - 1500314033501 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:53 INFO - 1500314033502 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:53 INFO - 1500314033505 Marionette DEBUG Register listener.js for window 15
10:53:53 INFO - 1500314033508 Marionette TRACE 356 <- [1,1,null,{"sessionId":"801d7670-6ac0-47c8-893c-052ba430fe49","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:53 INFO - 1500314033510 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:53:53 INFO - 1500314033512 Marionette TRACE 356 <- [1,2,null,{}]
10:53:53 INFO - 1500314033514 Marionette DEBUG Closed connection 356
10:53:53 INFO - 1500314033515 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50091
10:53:53 INFO - 1500314033516 Marionette DEBUG Closed connection 357
10:53:53 INFO - 1500314033516 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50092
10:53:53 INFO - 1500314033517 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:53:53 INFO - 1500314033526 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:53 INFO - 1500314033529 Marionette DEBUG Register listener.js for window 15
10:53:53 INFO - 1500314033532 Marionette TRACE 358 <- [1,1,null,{"sessionId":"35e052fb-1107-487d-8aa4-4c3f49f8a90c","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:53 INFO - 1500314033535 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49646/clicks.html"}]
10:53:53 INFO - 1500314033536 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49646/hidden.html"
10:53:53 INFO - 1500314033543 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49646/hidden.html"
10:53:53 INFO - 1500314033543 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49646/hidden.html"
10:53:53 INFO - 1500314033571 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49646/clicks.html"
10:53:53 INFO - 1500314033573 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49646/clicks.html"
10:53:53 INFO - 1500314033578 Marionette TRACE 358 <- [1,2,null,{}]
10:53:53 INFO - 1500314033586 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:53:53 INFO - 1500314033590 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a51ce761-c763-4777-a3a5-d257224121a0","ELEMENT":"a51ce761-c763-4777-a3a5-d257224121a0"}}]
10:53:53 INFO - 1500314033593 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a51ce761-c763-4777-a3a5-d257224121a0"}]
10:53:53 INFO - 1500314033641 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49646/clicks.html"
10:53:53 INFO - 1500314033654 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49646/clicks.html"
10:53:53 INFO - 1500314033655 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49646/clicks.html"
10:53:53 INFO - 1500314033689 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49646/test.html"
10:53:53 INFO - 1500314033693 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49646/test.html"
10:53:53 INFO - 1500314033698 Marionette TRACE 358 <- [1,4,null,{}]
10:53:53 INFO - 1500314033707 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:53:53 INFO - 1500314033710 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"604cc001-d42c-4bd7-abc9-d20fa2d17e02","ELEMENT":"604cc001-d42c-4bd7-abc9-d20fa2d17e02"}}]
10:53:53 INFO - 1500314033711 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:53:53 INFO - 1500314033711 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:53:53 INFO - 1500314033731 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
10:53:53 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500314033734 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:53:53 INFO - 1500314033735 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:53:53 INFO - 1500314033737 Marionette TRACE 358 <- [1,8,null,{}]
10:53:53 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 270ms
10:53:53 INFO - TEST-START | test_click.py TestClick.test_container_element
10:53:53 INFO - 1500314033739 Marionette DEBUG Closed connection 358
10:53:53 INFO - 1500314033740 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50095
10:53:53 INFO - 1500314033741 Marionette DEBUG Closed connection 359
10:53:53 INFO - 1500314033741 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50096
10:53:53 INFO - 1500314033743 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:53 INFO - 1500314033743 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:53 INFO - 1500314033746 Marionette DEBUG Register listener.js for window 15
10:53:53 INFO - 1500314033749 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3af8fd35-49f2-443e-80f8-a7724b19a993","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:53 INFO - 1500314033752 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:53:53 INFO - 1500314033752 Marionette TRACE 360 <- [1,2,null,{}]
10:53:53 INFO - 1500314033753 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:53:53 INFO - 1500314033754 Marionette TRACE 360 <- [1,3,null,{}]
10:53:53 INFO - 1500314033755 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:53:53 INFO - 1500314033755 Marionette TRACE 360 <- [1,4,null,{}]
10:53:53 INFO - 1500314033757 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
10:53:53 INFO - TEST-START: test_click.py TestClick.test_container_element1500314033760 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:53:53 INFO - 1500314033761 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:53:53 INFO - 1500314033762 Marionette TRACE 360 <- [1,6,null,{}]
10:53:53 INFO - 1500314033765 Marionette DEBUG Closed connection 360
10:53:53 INFO - 1500314033766 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50097
10:53:53 INFO - 1500314033766 Marionette DEBUG Closed connection 361
10:53:53 INFO - 1500314033767 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50098
10:53:53 INFO - 1500314033768 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:53 INFO - 1500314033769 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:53 INFO - 1500314033772 Marionette DEBUG Register listener.js for window 15
10:53:53 INFO - 1500314033775 Marionette TRACE 362 <- [1,1,null,{"sessionId":"09f558b6-6b10-443a-b935-c2a1d5e184a0","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:53 INFO - 1500314033777 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:53:53 INFO - 1500314033779 Marionette TRACE 362 <- [1,2,null,{}]
10:53:53 INFO - 1500314033781 Marionette DEBUG Closed connection 362
10:53:53 INFO - 1500314033782 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50099
10:53:53 INFO - 1500314033783 Marionette DEBUG Closed connection 363
10:53:53 INFO - 1500314033784 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50100
10:53:53 INFO - 1500314033785 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:53:53 INFO - 1500314033785 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:53 INFO - 1500314033788 Marionette DEBUG Register listener.js for window 15
10:53:53 INFO - 1500314033791 Marionette TRACE 364 <- [1,1,null,{"sessionId":"83e65792-2991-4517-8b61-8ffed54e1414","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:53 INFO - 1500314033793 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:53:53 INFO - 1500314033795 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49646/test.html"
10:53:53 INFO - 1500314033803 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49646/test.html"
10:53:53 INFO - 1500314033803 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49646/test.html"
10:53:53 INFO - 1500314033829 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:53 INFO - 1500314033837 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:53 INFO - 1500314033842 Marionette TRACE 364 <- [1,2,null,{}]
10:53:53 INFO - 1500314033843 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:53:53 INFO - 1500314033846 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ba38fec-32d4-4b1c-a7cb-b0727df0279c","ELEMENT":"2ba38fec-32d4-4b1c-a7cb-b0727df0279c"}}]
10:53:53 INFO - 1500314033853 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"2ba38fec-32d4-4b1c-a7cb-b0727df0279c"}]
10:53:54 INFO - 1500314034099 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:53:54 INFO - 1500314034100 Marionette TRACE 364 <- [1,4,null,{}]
10:53:54 INFO - 1500314034101 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"2ba38fec-32d4-4b1c-a7cb-b0727df0279c","name":"selected"}]
10:53:54 INFO - 1500314034106 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:53:54 INFO - 1500314034108 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
10:53:54 INFO - TEST-END: test_click.py TestClick.test_container_element1500314034111 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:53:54 INFO - 1500314034113 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:53:54 INFO - 1500314034115 Marionette TRACE 364 <- [1,7,null,{}]
10:53:54 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 378ms
10:53:54 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:53:54 INFO - 1500314034117 Marionette DEBUG Closed connection 364
10:53:54 INFO - 1500314034118 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50101
10:53:54 INFO - 1500314034119 Marionette DEBUG Closed connection 365
10:53:54 INFO - 1500314034120 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50102
10:53:54 INFO - 1500314034121 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:54 INFO - 1500314034121 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034124 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034128 Marionette TRACE 366 <- [1,1,null,{"sessionId":"5af11865-3128-4286-92b8-64c43129605d","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034130 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:53:54 INFO - 1500314034131 Marionette TRACE 366 <- [1,2,null,{}]
10:53:54 INFO - 1500314034132 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:53:54 INFO - 1500314034132 Marionette TRACE 366 <- [1,3,null,{}]
10:53:54 INFO - 1500314034133 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:53:54 INFO - 1500314034134 Marionette TRACE 366 <- [1,4,null,{}]
10:53:54 INFO - 1500314034143 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
10:53:54 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500314034146 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:53:54 INFO - 1500314034147 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:53:54 INFO - 1500314034148 Marionette TRACE 366 <- [1,6,null,{}]
10:53:54 INFO - 1500314034151 Marionette DEBUG Closed connection 366
10:53:54 INFO - 1500314034151 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50103
10:53:54 INFO - 1500314034152 Marionette DEBUG Closed connection 367
10:53:54 INFO - 1500314034153 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50104
10:53:54 INFO - 1500314034154 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:54 INFO - 1500314034155 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034157 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034161 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b72a2d98-75cf-4005-aee3-50fdb7dc33ea","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034163 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:53:54 INFO - 1500314034164 Marionette TRACE 368 <- [1,2,null,{}]
10:53:54 INFO - 1500314034167 Marionette DEBUG Closed connection 368
10:53:54 INFO - 1500314034168 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50105
10:53:54 INFO - 1500314034168 Marionette DEBUG Closed connection 369
10:53:54 INFO - 1500314034169 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50106
10:53:54 INFO - 1500314034170 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:53:54 INFO - 1500314034171 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034174 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034185 Marionette TRACE 370 <- [1,1,null,{"sessionId":"8f7024bc-9736-41e6-99fd-5f2936579dd0","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034187 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:53:54 INFO - 1500314034189 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034193 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034194 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034216 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034219 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034223 Marionette TRACE 370 <- [1,2,null,{}]
10:53:54 INFO - 1500314034224 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:53:54 INFO - 1500314034227 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"188b7aaa-3b3d-4ad7-9b03-ec3bf90c4772","ELEMENT":"188b7aaa-3b3d-4ad7-9b03-ec3bf90c4772"}}]
10:53:54 INFO - 1500314034228 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"188b7aaa-3b3d-4ad7-9b03-ec3bf90c4772"}]
10:53:54 INFO - 1500314034479 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:53:54 INFO - 1500314034480 Marionette TRACE 370 <- [1,4,null,{}]
10:53:54 INFO - 1500314034481 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"188b7aaa-3b3d-4ad7-9b03-ec3bf90c4772","name":"selected"}]
10:53:54 INFO - 1500314034486 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:53:54 INFO - 1500314034488 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
10:53:54 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500314034491 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:53:54 INFO - 1500314034492 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:53:54 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 378ms
10:53:54 INFO - 1500314034494 Marionette TRACE 370 <- [1,7,null,{}]
10:53:54 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:53:54 INFO - 1500314034496 Marionette DEBUG Closed connection 370
10:53:54 INFO - 1500314034497 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50107
10:53:54 INFO - 1500314034498 Marionette DEBUG Closed connection 371
10:53:54 INFO - 1500314034499 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50108
10:53:54 INFO - 1500314034500 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:54 INFO - 1500314034501 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034504 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034507 Marionette TRACE 372 <- [1,1,null,{"sessionId":"b95f0e5a-d729-4086-9f0a-13f6df089df4","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034509 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:53:54 INFO - 1500314034510 Marionette TRACE 372 <- [1,2,null,{}]
10:53:54 INFO - 1500314034511 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:53:54 INFO - 1500314034511 Marionette TRACE 372 <- [1,3,null,{}]
10:53:54 INFO - 1500314034512 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:53:54 INFO - 1500314034513 Marionette TRACE 372 <- [1,4,null,{}]
10:53:54 INFO - 1500314034515 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
10:53:54 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500314034518 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:53:54 INFO - 1500314034519 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:53:54 INFO - 1500314034520 Marionette TRACE 372 <- [1,6,null,{}]
10:53:54 INFO - 1500314034523 Marionette DEBUG Closed connection 372
10:53:54 INFO - 1500314034523 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50109
10:53:54 INFO - 1500314034524 Marionette DEBUG Closed connection 373
10:53:54 INFO - 1500314034525 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50110
10:53:54 INFO - 1500314034526 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:54 INFO - 1500314034527 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034529 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034533 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d7e4d598-00f2-4d10-88a6-67ef1ef65733","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034535 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:53:54 INFO - 1500314034536 Marionette TRACE 374 <- [1,2,null,{}]
10:53:54 INFO - 1500314034538 Marionette DEBUG Closed connection 374
10:53:54 INFO - 1500314034539 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50111
10:53:54 INFO - 1500314034540 Marionette DEBUG Closed connection 375
10:53:54 INFO - 1500314034541 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50112
10:53:54 INFO - 1500314034542 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:53:54 INFO - 1500314034543 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034545 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034549 Marionette TRACE 376 <- [1,1,null,{"sessionId":"986e2212-3ca1-4efb-b1a5-d8f2d3ec7941","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034552 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
10:53:54 INFO - 1500314034553 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034556 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034556 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034579 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:53:54 INFO - 1500314034583 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:53:54 INFO - 1500314034594 Marionette TRACE 376 <- [1,2,null,{}]
10:53:54 INFO - 1500314034596 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:53:54 INFO - 1500314034600 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"af3485d4-ab50-4da4-a3dd-05204aba8a5a","ELEMENT":"af3485d4-ab50-4da4-a3dd-05204aba8a5a"}}]
10:53:54 INFO - 1500314034601 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"af3485d4-ab50-4da4-a3dd-05204aba8a5a"}]
10:53:54 INFO - 1500314034845 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:53:54 INFO - 1500314034845 Marionette TRACE 376 <- [1,4,null,{}]
10:53:54 INFO - 1500314034847 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
10:53:54 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500314034851 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:53:54 INFO - 1500314034852 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:53:54 INFO - 1500314034853 Marionette TRACE 376 <- [1,6,null,{}]
10:53:54 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 358ms
10:53:54 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:53:54 INFO - 1500314034855 Marionette DEBUG Closed connection 376
10:53:54 INFO - 1500314034856 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50113
10:53:54 INFO - 1500314034857 Marionette DEBUG Closed connection 377
10:53:54 INFO - 1500314034858 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50114
10:53:54 INFO - 1500314034859 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:54 INFO - 1500314034860 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034863 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034866 Marionette TRACE 378 <- [1,1,null,{"sessionId":"a67c9912-12e0-4bdf-b284-e0907d9e2a0f","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034868 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:53:54 INFO - 1500314034869 Marionette TRACE 378 <- [1,2,null,{}]
10:53:54 INFO - 1500314034870 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:53:54 INFO - 1500314034870 Marionette TRACE 378 <- [1,3,null,{}]
10:53:54 INFO - 1500314034871 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:53:54 INFO - 1500314034872 Marionette TRACE 378 <- [1,4,null,{}]
10:53:54 INFO - 1500314034874 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
10:53:54 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500314034877 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:53:54 INFO - 1500314034878 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:53:54 INFO - 1500314034879 Marionette TRACE 378 <- [1,6,null,{}]
10:53:54 INFO - 1500314034882 Marionette DEBUG Closed connection 378
10:53:54 INFO - 1500314034882 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50115
10:53:54 INFO - 1500314034883 Marionette DEBUG Closed connection 379
10:53:54 INFO - 1500314034884 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50116
10:53:54 INFO - 1500314034885 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:54 INFO - 1500314034886 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034889 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034892 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b802a1c7-1a2c-4d37-bb41-30c83c30dd08","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034894 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:53:54 INFO - 1500314034896 Marionette TRACE 380 <- [1,2,null,{}]
10:53:54 INFO - 1500314034898 Marionette DEBUG Closed connection 380
10:53:54 INFO - 1500314034899 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50117
10:53:54 INFO - 1500314034900 Marionette DEBUG Closed connection 381
10:53:54 INFO - 1500314034901 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50118
10:53:54 INFO - 1500314034902 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:53:54 INFO - 1500314034902 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:54 INFO - 1500314034905 Marionette DEBUG Register listener.js for window 15
10:53:54 INFO - 1500314034908 Marionette TRACE 382 <- [1,1,null,{"sessionId":"4e0c67e4-039e-407c-a71b-e2a43dd3f486","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:54 INFO - 1500314034919 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
10:53:54 INFO - 1500314034921 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:53:54 INFO - 1500314034924 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:53:54 INFO - 1500314034924 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
10:53:54 INFO - 1500314034944 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034948 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:54 INFO - 1500314034959 Marionette TRACE 382 <- [1,2,null,{}]
10:53:54 INFO - 1500314034960 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:53:54 INFO - 1500314034963 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d50b23b-b7ef-4d2a-ba24-d97aca969667","ELEMENT":"8d50b23b-b7ef-4d2a-ba24-d97aca969667"}}]
10:53:54 INFO - 1500314034965 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"8d50b23b-b7ef-4d2a-ba24-d97aca969667"}]
10:53:55 INFO - 1500314035210 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:53:55 INFO - 1500314035210 Marionette TRACE 382 <- [1,4,null,{}]
10:53:55 INFO - 1500314035212 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
10:53:55 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 364ms
10:53:55 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500314035215 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:53:55 INFO - 1500314035216 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:53:55 INFO - 1500314035218 Marionette TRACE 382 <- [1,6,null,{}]
10:53:55 INFO - TEST-START | test_click.py TestClick.test_input_file
10:53:55 INFO - 1500314035220 Marionette DEBUG Closed connection 382
10:53:55 INFO - 1500314035222 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50119
10:53:55 INFO - 1500314035223 Marionette DEBUG Closed connection 383
10:53:55 INFO - 1500314035224 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50120
10:53:55 INFO - 1500314035225 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:55 INFO - 1500314035225 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:55 INFO - 1500314035228 Marionette DEBUG Register listener.js for window 15
10:53:55 INFO - 1500314035232 Marionette TRACE 384 <- [1,1,null,{"sessionId":"88fd3be0-ebac-47cb-b876-7de157533719","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:55 INFO - 1500314035234 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:53:55 INFO - 1500314035234 Marionette TRACE 384 <- [1,2,null,{}]
10:53:55 INFO - 1500314035235 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:53:55 INFO - 1500314035236 Marionette TRACE 384 <- [1,3,null,{}]
10:53:55 INFO - 1500314035237 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:53:55 INFO - 1500314035237 Marionette TRACE 384 <- [1,4,null,{}]
10:53:55 INFO - 1500314035239 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
10:53:55 INFO - TEST-START: test_click.py TestClick.test_input_file1500314035242 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:53:55 INFO - 1500314035243 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:53:55 INFO - 1500314035244 Marionette TRACE 384 <- [1,6,null,{}]
10:53:55 INFO - 1500314035246 Marionette DEBUG Closed connection 384
10:53:55 INFO - 1500314035247 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50121
10:53:55 INFO - 1500314035248 Marionette DEBUG Closed connection 385
10:53:55 INFO - 1500314035249 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50122
10:53:55 INFO - 1500314035250 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:55 INFO - 1500314035250 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:55 INFO - 1500314035253 Marionette DEBUG Register listener.js for window 15
10:53:55 INFO - 1500314035256 Marionette TRACE 386 <- [1,1,null,{"sessionId":"16f0daa0-2ddd-441a-a77d-f441d64773d9","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:55 INFO - 1500314035259 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:53:55 INFO - 1500314035260 Marionette TRACE 386 <- [1,2,null,{}]
10:53:55 INFO - 1500314035262 Marionette DEBUG Closed connection 386
10:53:55 INFO - 1500314035263 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50123
10:53:55 INFO - 1500314035264 Marionette DEBUG Closed connection 387
10:53:55 INFO - 1500314035265 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50124
10:53:55 INFO - 1500314035266 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:53:55 INFO - 1500314035267 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:55 INFO - 1500314035269 Marionette DEBUG Register listener.js for window 15
10:53:55 INFO - 1500314035272 Marionette TRACE 388 <- [1,1,null,{"sessionId":"f1629975-9dc1-44d8-a85a-a5be2696e4cb","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:55 INFO - 1500314035275 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:53:55 INFO - 1500314035276 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:55 INFO - 1500314035279 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:55 INFO - 1500314035280 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
10:53:55 INFO - 1500314035301 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:53:55 INFO - 1500314035305 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:53:55 INFO - 1500314035308 Marionette TRACE 388 <- [1,2,null,{}]
10:53:55 INFO - 1500314035310 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:53:55 INFO - 1500314035319 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d4d66df5-72c1-4aef-9b90-a0fcd067f575","ELEMENT":"d4d66df5-72c1-4aef-9b90-a0fcd067f575"}}]
10:53:55 INFO - 1500314035321 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d4d66df5-72c1-4aef-9b90-a0fcd067f575"}]
10:53:55 INFO - 1500314035351 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:53:55 INFO - 1500314035354 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
10:53:55 INFO - TEST-END: test_click.py TestClick.test_input_file1500314035357 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:53:55 INFO - 1500314035358 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:53:55 INFO - 1500314035359 Marionette TRACE 388 <- [1,6,null,{}]
10:53:55 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 141ms
10:53:55 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:53:55 INFO - 1500314035362 Marionette DEBUG Closed connection 388
10:53:55 INFO - 1500314035363 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50125
10:53:55 INFO - 1500314035366 Marionette DEBUG Closed connection 389
10:53:55 INFO - 1500314035366 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50126
10:53:55 INFO - 1500314035379 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:55 INFO - 1500314035379 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:55 INFO - 1500314035382 Marionette DEBUG Register listener.js for window 15
10:53:55 INFO - 1500314035386 Marionette TRACE 390 <- [1,1,null,{"sessionId":"84cb9071-af66-46d9-9909-0c5db26744e0","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:55 INFO - 1500314035388 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:53:55 INFO - 1500314035389 Marionette TRACE 390 <- [1,2,null,{}]
10:53:55 INFO - 1500314035390 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:53:55 INFO - 1500314035390 Marionette TRACE 390 <- [1,3,null,{}]
10:53:55 INFO - 1500314035391 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:53:55 INFO - 1500314035392 Marionette TRACE 390 <- [1,4,null,{}]
10:53:55 INFO - 1500314035393 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
10:53:55 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500314035396 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:53:55 INFO - 1500314035397 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:53:55 INFO - 1500314035399 Marionette TRACE 390 <- [1,6,null,{}]
10:53:55 INFO - 1500314035401 Marionette DEBUG Closed connection 390
10:53:55 INFO - 1500314035402 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50127
10:53:55 INFO - 1500314035403 Marionette DEBUG Closed connection 391
10:53:55 INFO - 1500314035403 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50128
10:53:55 INFO - 1500314035405 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:55 INFO - 1500314035405 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:55 INFO - 1500314035408 Marionette DEBUG Register listener.js for window 15
10:53:55 INFO - 1500314035411 Marionette TRACE 392 <- [1,1,null,{"sessionId":"7c234001-e322-490e-a44b-6f2d333307a4","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:55 INFO - 1500314035489 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:53:55 INFO - 1500314035492 Marionette TRACE 392 <- [1,2,null,{}]
10:53:55 INFO - 1500314035495 Marionette DEBUG Closed connection 392
10:53:55 INFO - 1500314035496 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50129
10:53:55 INFO - 1500314035498 Marionette DEBUG Closed connection 393
10:53:55 INFO - 1500314035499 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50130
10:53:55 INFO - 1500314035501 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:53:55 INFO - 1500314035504 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:55 INFO - 1500314035507 Marionette DEBUG Register listener.js for window 15
10:53:55 INFO - 1500314035513 Marionette TRACE 394 <- [1,1,null,{"sessionId":"fa8c4f75-c785-430a-8304-8d411a8f6d09","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:55 INFO - 1500314035561 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
10:53:55 INFO - 1500314035563 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:53:55 INFO - 1500314035567 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:53:55 INFO - 1500314035568 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:53:55 INFO - 1500314035596 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:53:55 INFO - 1500314035599 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:53:55 INFO - 1500314035611 Marionette TRACE 394 <- [1,2,null,{}]
10:53:55 INFO - 1500314035613 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:53:55 INFO - 1500314035619 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a0899b71-7913-4711-b497-1c1be0fabaf2","ELEMENT":"a0899b71-7913-4711-b497-1c1be0fabaf2"}}]
10:53:55 INFO - 1500314035620 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:53:55 INFO - 1500314035624 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0d4d79af-670e-483f-afe7-212f6fc06419","ELEMENT":"0d4d79af-670e-483f-afe7-212f6fc06419"}}]
10:53:55 INFO - 1500314035626 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a0899b71-7913-4711-b497-1c1be0fabaf2"}]
10:53:55 INFO - 1500314035875 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:53:55 INFO - 1500314035876 Marionette TRACE 394 <- [1,5,null,{}]
10:53:55 INFO - 1500314035879 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"0d4d79af-670e-483f-afe7-212f6fc06419"}]
10:53:55 INFO - 1500314035901 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
10:53:55 INFO - 1500314035903 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:53:55 INFO - 1500314035908 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:53:55 INFO - 1500314035910 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
10:53:55 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500314035915 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:53:55 INFO - 1500314035916 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:53:55 INFO - 1500314035919 Marionette TRACE 394 <- [1,9,null,{}]
10:53:55 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 559ms
10:53:55 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:53:55 INFO - 1500314035930 Marionette DEBUG Closed connection 394
10:53:55 INFO - 1500314035931 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50131
10:53:55 INFO - 1500314035933 Marionette DEBUG Closed connection 395
10:53:55 INFO - 1500314035934 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50132
10:53:55 INFO - 1500314035936 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:55 INFO - 1500314035938 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:55 INFO - 1500314035942 Marionette DEBUG Register listener.js for window 15
10:53:55 INFO - 1500314035948 Marionette TRACE 396 <- [1,1,null,{"sessionId":"82887a67-b7ae-4451-bfa7-c4d3fb142a88","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:55 INFO - 1500314035952 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:53:55 INFO - 1500314035953 Marionette TRACE 396 <- [1,2,null,{}]
10:53:55 INFO - 1500314035954 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:53:55 INFO - 1500314035955 Marionette TRACE 396 <- [1,3,null,{}]
10:53:55 INFO - 1500314035957 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:53:55 INFO - 1500314035957 Marionette TRACE 396 <- [1,4,null,{}]
10:53:55 INFO - 1500314035960 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
10:53:55 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500314035964 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:53:55 INFO - 1500314035967 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:53:55 INFO - 1500314035969 Marionette TRACE 396 <- [1,6,null,{}]
10:53:55 INFO - 1500314035973 Marionette DEBUG Closed connection 396
10:53:55 INFO - 1500314035974 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50133
10:53:55 INFO - 1500314035975 Marionette DEBUG Closed connection 397
10:53:55 INFO - 1500314035976 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50134
10:53:55 INFO - 1500314035979 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:53:55 INFO - 1500314035980 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:55 INFO - 1500314035983 Marionette DEBUG Register listener.js for window 15
10:53:55 INFO - 1500314035988 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b1df5950-2e76-4fb5-984b-6fcb1a689d97","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:55 INFO - 1500314035991 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:53:55 INFO - 1500314035993 Marionette TRACE 398 <- [1,2,null,{}]
10:53:55 INFO - 1500314035996 Marionette DEBUG Closed connection 398
10:53:55 INFO - 1500314035997 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50135
10:53:56 INFO - 1500314035998 Marionette DEBUG Closed connection 399
10:53:56 INFO - 1500314035999 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50136
10:53:56 INFO - 1500314036000 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:53:56 INFO - 1500314036001 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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}
10:53:56 INFO - 1500314036004 Marionette DEBUG Register listener.js for window 15
10:53:56 INFO - 1500314036008 Marionette TRACE 400 <- [1,1,null,{"sessionId":"21394402-16f6-4749-995c-1d176c8075ee","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":416,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmph1skm6.mozrunner","moz:accessibilityChecks":false}}]
10:53:56 INFO - 1500314036011 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
10:53:56 INFO - 1500314036015 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:53:56 INFO - 1500314036019 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:53:56 INFO - 1500314036019 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
10:53:56 INFO - 1500314036045 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
10:53:56 INFO - 1500314036048 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
10:53:56 INFO - 1500314036070 Marionette TRACE 400 <- [1,2,null,{}]
10:53:56 INFO - 1500314036072 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:53:56 INFO - 1500314036076 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"549bc770-84e9-4454-a651-4560a7c903b3","ELEMENT":"549bc770-84e9-4454-a651-4560a7c903b3"}}]
10:53:56 INFO - 1500314036077 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"549bc770-84e9-4454-a651-4560a7c903b3"}]
10:53:56 INFO - 1500314036100 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:53:56 INFO - 1500314036101 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"549bc770-84e9-4454-a651-4560a7c903b3"}]
10:53:56 INFO - 1500314036125 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element