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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
07:05:47 INFO - 1500991547520 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}]
07:05:47 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500991547523 Marionette TRACE 352 <- [1,5,null,{"value":null}]
07:05:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 162ms
07:05:47 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
07:05:47 INFO - 1500991547532 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
07:05:47 INFO - 1500991547533 Marionette TRACE 352 <- [1,6,null,{}]
07:05:47 INFO - 1500991547536 Marionette DEBUG Closed connection 352
07:05:47 INFO - 1500991547537 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50269
07:05:47 INFO - 1500991547537 Marionette DEBUG Closed connection 353
07:05:47 INFO - 1500991547538 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50270
07:05:47 INFO - 1500991547539 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:47 INFO - 1500991547540 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:47 INFO - 1500991547542 Marionette DEBUG Register listener.js for window 12
07:05:47 INFO - 1500991547546 Marionette TRACE 354 <- [1,1,null,{"sessionId":"c3362c78-b487-4d6d-8dfc-483f3c5df4e9","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:47 INFO - 1500991547548 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
07:05:47 INFO - 1500991547549 Marionette TRACE 354 <- [1,2,null,{}]
07:05:47 INFO - 1500991547550 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:05:47 INFO - 1500991547550 Marionette TRACE 354 <- [1,3,null,{}]
07:05:47 INFO - 1500991547551 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
07:05:47 INFO - 1500991547552 Marionette TRACE 354 <- [1,4,null,{}]
07:05:47 INFO - 1500991547554 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}]
07:05:47 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500991547557 Marionette TRACE 354 <- [1,5,null,{"value":null}]
07:05:47 INFO - 1500991547558 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
07:05:47 INFO - 1500991547560 Marionette TRACE 354 <- [1,6,null,{}]
07:05:47 INFO - 1500991547563 Marionette DEBUG Closed connection 354
07:05:47 INFO - 1500991547563 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50271
07:05:47 INFO - 1500991547564 Marionette DEBUG Closed connection 355
07:05:47 INFO - 1500991547565 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50272
07:05:47 INFO - 1500991547566 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:47 INFO - 1500991547567 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:47 INFO - 1500991547569 Marionette DEBUG Register listener.js for window 12
07:05:47 INFO - 1500991547572 Marionette TRACE 356 <- [1,1,null,{"sessionId":"ca56154a-a1e2-4edc-8910-90d7e070f864","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:47 INFO - 1500991547575 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
07:05:47 INFO - 1500991547576 Marionette TRACE 356 <- [1,2,null,{}]
07:05:47 INFO - 1500991547578 Marionette DEBUG Closed connection 356
07:05:47 INFO - 1500991547579 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50273
07:05:47 INFO - 1500991547580 Marionette DEBUG Closed connection 357
07:05:47 INFO - 1500991547581 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50274
07:05:47 INFO - 1500991547582 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:05:47 INFO - 1500991547583 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:47 INFO - 1500991547584 Marionette DEBUG Register listener.js for window 12
07:05:47 INFO - 1500991547588 Marionette TRACE 358 <- [1,1,null,{"sessionId":"206e0656-cd12-43dc-89b1-b7f713192d69","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:47 INFO - 1500991547610 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49833/clicks.html"}]
07:05:47 INFO - 1500991547612 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49833/hidden.html"
07:05:47 INFO - 1500991547623 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49833/hidden.html"
07:05:47 INFO - 1500991547623 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49833/hidden.html"
07:05:47 INFO - 1500991547648 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49833/clicks.html"
07:05:47 INFO - 1500991547650 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49833/clicks.html"
07:05:47 INFO - 1500991547662 Marionette TRACE 358 <- [1,2,null,{}]
07:05:47 INFO - 1500991547663 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
07:05:47 INFO - 1500991547666 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e31e897d-5796-4bcd-9507-89d72cfcaf1a","ELEMENT":"e31e897d-5796-4bcd-9507-89d72cfcaf1a"}}]
07:05:47 INFO - 1500991547676 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"e31e897d-5796-4bcd-9507-89d72cfcaf1a"}]
07:05:47 INFO - 1500991547721 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49833/clicks.html"
07:05:47 INFO - 1500991547729 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49833/clicks.html"
07:05:47 INFO - 1500991547730 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49833/clicks.html"
07:05:47 INFO - 1500991547778 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49833/test.html"
07:05:47 INFO - 1500991547783 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49833/test.html"
07:05:47 INFO - 1500991547795 Marionette TRACE 358 <- [1,4,null,{}]
07:05:47 INFO - 1500991547797 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
07:05:47 INFO - 1500991547808 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ecaca69d-c0c0-411b-8969-7eb782e2a01e","ELEMENT":"ecaca69d-c0c0-411b-8969-7eb782e2a01e"}}]
07:05:47 INFO - 1500991547810 Marionette TRACE 358 -> [0,6,"getTitle",{}]
07:05:47 INFO - 1500991547810 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
07:05:47 INFO - 1500991547826 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}]
07:05:47 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500991547829 Marionette TRACE 358 <- [1,7,null,{"value":null}]
07:05:47 INFO - 1500991547830 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
07:05:47 INFO - 1500991547831 Marionette TRACE 358 <- [1,8,null,{}]
07:05:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 301ms
07:05:47 INFO - TEST-START | test_click.py TestClick.test_container_element
07:05:47 INFO - 1500991547837 Marionette DEBUG Closed connection 358
07:05:47 INFO - 1500991547838 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50277
07:05:47 INFO - 1500991547849 Marionette DEBUG Closed connection 359
07:05:47 INFO - 1500991547850 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50278
07:05:47 INFO - 1500991547859 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:47 INFO - 1500991547859 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:47 INFO - 1500991547861 Marionette DEBUG Register listener.js for window 12
07:05:47 INFO - 1500991547865 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d5c5aea9-daf9-4d94-b8e3-d9e0418b136f","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:47 INFO - 1500991547868 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
07:05:47 INFO - 1500991547869 Marionette TRACE 360 <- [1,2,null,{}]
07:05:47 INFO - 1500991547870 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:05:47 INFO - 1500991547870 Marionette TRACE 360 <- [1,3,null,{}]
07:05:47 INFO - 1500991547871 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
07:05:47 INFO - 1500991547872 Marionette TRACE 360 <- [1,4,null,{}]
07:05:47 INFO - 1500991547874 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}]
07:05:47 INFO - TEST-START: test_click.py TestClick.test_container_element1500991547877 Marionette TRACE 360 <- [1,5,null,{"value":null}]
07:05:47 INFO - 1500991547878 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
07:05:47 INFO - 1500991547880 Marionette TRACE 360 <- [1,6,null,{}]
07:05:47 INFO - 1500991547882 Marionette DEBUG Closed connection 360
07:05:47 INFO - 1500991547883 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50279
07:05:47 INFO - 1500991547886 Marionette DEBUG Closed connection 361
07:05:47 INFO - 1500991547887 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50280
07:05:47 INFO - 1500991547888 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:47 INFO - 1500991547888 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:47 INFO - 1500991547891 Marionette DEBUG Register listener.js for window 12
07:05:47 INFO - 1500991547894 Marionette TRACE 362 <- [1,1,null,{"sessionId":"bbb3ef0d-7026-418d-8336-6653e0822cba","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:47 INFO - 1500991547896 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
07:05:47 INFO - 1500991547898 Marionette TRACE 362 <- [1,2,null,{}]
07:05:47 INFO - 1500991547900 Marionette DEBUG Closed connection 362
07:05:47 INFO - 1500991547901 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50281
07:05:47 INFO - 1500991547902 Marionette DEBUG Closed connection 363
07:05:47 INFO - 1500991547903 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50282
07:05:47 INFO - 1500991547904 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:05:47 INFO - 1500991547905 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:47 INFO - 1500991547907 Marionette DEBUG Register listener.js for window 12
07:05:47 INFO - 1500991547910 Marionette TRACE 364 <- [1,1,null,{"sessionId":"2f4192e2-027b-4d10-b508-7c46f2d40409","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:47 INFO - 1500991547912 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"}]
07:05:47 INFO - 1500991547914 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49833/test.html"
07:05:47 INFO - 1500991547917 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49833/test.html"
07:05:47 INFO - 1500991547917 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49833/test.html"
07:05:47 INFO - 1500991547944 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"
07:05:47 INFO - 1500991547948 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"
07:05:47 INFO - 1500991547952 Marionette TRACE 364 <- [1,2,null,{}]
07:05:47 INFO - 1500991547961 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:05:47 INFO - 1500991547964 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"93109b50-a812-472b-9c4a-52316d8af6d7","ELEMENT":"93109b50-a812-472b-9c4a-52316d8af6d7"}}]
07:05:47 INFO - 1500991547965 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"93109b50-a812-472b-9c4a-52316d8af6d7"}]
07:05:48 INFO - 1500991548212 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:05:48 INFO - 1500991548212 Marionette TRACE 364 <- [1,4,null,{}]
07:05:48 INFO - 1500991548214 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"93109b50-a812-472b-9c4a-52316d8af6d7","name":"selected"}]
07:05:48 INFO - 1500991548218 Marionette TRACE 364 <- [1,5,null,{"value":true}]
07:05:48 INFO - 1500991548220 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}]
07:05:48 INFO - TEST-END: test_click.py TestClick.test_container_element1500991548223 Marionette TRACE 364 <- [1,6,null,{"value":null}]
07:05:48 INFO - 1500991548224 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
07:05:48 INFO - 1500991548226 Marionette TRACE 364 <- [1,7,null,{}]
07:05:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 390ms
07:05:48 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
07:05:48 INFO - 1500991548228 Marionette DEBUG Closed connection 364
07:05:48 INFO - 1500991548229 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50283
07:05:48 INFO - 1500991548230 Marionette DEBUG Closed connection 365
07:05:48 INFO - 1500991548231 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50284
07:05:48 INFO - 1500991548232 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:48 INFO - 1500991548233 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:48 INFO - 1500991548235 Marionette DEBUG Register listener.js for window 12
07:05:48 INFO - 1500991548238 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1641afe3-a1ff-4892-9930-b562bde85e5f","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:48 INFO - 1500991548241 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
07:05:48 INFO - 1500991548241 Marionette TRACE 366 <- [1,2,null,{}]
07:05:48 INFO - 1500991548242 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:05:48 INFO - 1500991548243 Marionette TRACE 366 <- [1,3,null,{}]
07:05:48 INFO - 1500991548244 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
07:05:48 INFO - 1500991548244 Marionette TRACE 366 <- [1,4,null,{}]
07:05:48 INFO - 1500991548246 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}]
07:05:48 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500991548249 Marionette TRACE 366 <- [1,5,null,{"value":null}]
07:05:48 INFO - 1500991548250 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
07:05:48 INFO - 1500991548252 Marionette TRACE 366 <- [1,6,null,{}]
07:05:48 INFO - 1500991548254 Marionette DEBUG Closed connection 366
07:05:48 INFO - 1500991548255 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50285
07:05:48 INFO - 1500991548255 Marionette DEBUG Closed connection 367
07:05:48 INFO - 1500991548256 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50286
07:05:48 INFO - 1500991548257 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:48 INFO - 1500991548258 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:48 INFO - 1500991548260 Marionette DEBUG Register listener.js for window 12
07:05:48 INFO - 1500991548263 Marionette TRACE 368 <- [1,1,null,{"sessionId":"44d5eefb-b904-4743-ba21-549801257065","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:48 INFO - 1500991548265 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
07:05:48 INFO - 1500991548267 Marionette TRACE 368 <- [1,2,null,{}]
07:05:48 INFO - 1500991548269 Marionette DEBUG Closed connection 368
07:05:48 INFO - 1500991548270 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50287
07:05:48 INFO - 1500991548271 Marionette DEBUG Closed connection 369
07:05:48 INFO - 1500991548272 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50288
07:05:48 INFO - 1500991548273 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:05:48 INFO - 1500991548273 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:48 INFO - 1500991548275 Marionette DEBUG Register listener.js for window 12
07:05:48 INFO - 1500991548279 Marionette TRACE 370 <- [1,1,null,{"sessionId":"22ffcd79-3be1-4313-978f-3402cfa1ffa1","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:48 INFO - 1500991548281 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"}]
07:05:48 INFO - 1500991548283 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"
07:05:48 INFO - 1500991548286 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"
07:05:48 INFO - 1500991548287 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"
07:05:48 INFO - 1500991548309 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"
07:05:48 INFO - 1500991548310 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"
07:05:48 INFO - 1500991548314 Marionette TRACE 370 <- [1,2,null,{}]
07:05:48 INFO - 1500991548316 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:05:48 INFO - 1500991548325 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"19ccc6e0-f35d-4ab6-903f-27bfddb8aa9d","ELEMENT":"19ccc6e0-f35d-4ab6-903f-27bfddb8aa9d"}}]
07:05:48 INFO - 1500991548326 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"19ccc6e0-f35d-4ab6-903f-27bfddb8aa9d"}]
07:05:48 INFO - 1500991548571 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:05:48 INFO - 1500991548572 Marionette TRACE 370 <- [1,4,null,{}]
07:05:48 INFO - 1500991548573 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"19ccc6e0-f35d-4ab6-903f-27bfddb8aa9d","name":"selected"}]
07:05:48 INFO - 1500991548577 Marionette TRACE 370 <- [1,5,null,{"value":true}]
07:05:48 INFO - 1500991548579 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}]
07:05:48 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500991548585 Marionette TRACE 370 <- [1,6,null,{"value":null}]
07:05:48 INFO - 1500991548587 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
07:05:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 362ms
07:05:48 INFO - TEST-START | test_click.py TestClick.test_css_transforms
07:05:48 INFO - 1500991548589 Marionette TRACE 370 <- [1,7,null,{}]
07:05:48 INFO - 1500991548591 Marionette DEBUG Closed connection 370
07:05:48 INFO - 1500991548592 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50289
07:05:48 INFO - 1500991548592 Marionette DEBUG Closed connection 371
07:05:48 INFO - 1500991548593 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50290
07:05:48 INFO - 1500991548594 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:48 INFO - 1500991548595 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:48 INFO - 1500991548597 Marionette DEBUG Register listener.js for window 12
07:05:48 INFO - 1500991548608 Marionette TRACE 372 <- [1,1,null,{"sessionId":"1288aee4-a03b-4afc-abca-f304ac2c34d2","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:48 INFO - 1500991548611 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
07:05:48 INFO - 1500991548611 Marionette TRACE 372 <- [1,2,null,{}]
07:05:48 INFO - 1500991548612 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:05:48 INFO - 1500991548613 Marionette TRACE 372 <- [1,3,null,{}]
07:05:48 INFO - 1500991548614 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
07:05:48 INFO - 1500991548615 Marionette TRACE 372 <- [1,4,null,{}]
07:05:48 INFO - 1500991548616 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}]
07:05:48 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500991548619 Marionette TRACE 372 <- [1,5,null,{"value":null}]
07:05:48 INFO - 1500991548620 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
07:05:48 INFO - 1500991548622 Marionette TRACE 372 <- [1,6,null,{}]
07:05:48 INFO - 1500991548624 Marionette DEBUG Closed connection 372
07:05:48 INFO - 1500991548625 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50291
07:05:48 INFO - 1500991548626 Marionette DEBUG Closed connection 373
07:05:48 INFO - 1500991548626 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50292
07:05:48 INFO - 1500991548627 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:48 INFO - 1500991548628 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:48 INFO - 1500991548630 Marionette DEBUG Register listener.js for window 12
07:05:48 INFO - 1500991548634 Marionette TRACE 374 <- [1,1,null,{"sessionId":"2d6bea32-85a3-4749-b483-2801d086b521","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:48 INFO - 1500991548636 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
07:05:48 INFO - 1500991548637 Marionette TRACE 374 <- [1,2,null,{}]
07:05:48 INFO - 1500991548639 Marionette DEBUG Closed connection 374
07:05:48 INFO - 1500991548640 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50293
07:05:48 INFO - 1500991548641 Marionette DEBUG Closed connection 375
07:05:48 INFO - 1500991548642 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50294
07:05:48 INFO - 1500991548643 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:05:48 INFO - 1500991548643 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:48 INFO - 1500991548646 Marionette DEBUG Register listener.js for window 12
07:05:48 INFO - 1500991548649 Marionette TRACE 376 <- [1,1,null,{"sessionId":"ed271286-7506-4ef9-a77d-ec1f5f292958","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:48 INFO - 1500991548652 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"}]
07:05:48 INFO - 1500991548653 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"
07:05:48 INFO - 1500991548656 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"
07:05:48 INFO - 1500991548656 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"
07:05:48 INFO - 1500991548678 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"
07:05:48 INFO - 1500991548681 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"
07:05:48 INFO - 1500991548685 Marionette TRACE 376 <- [1,2,null,{}]
07:05:48 INFO - 1500991548694 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
07:05:48 INFO - 1500991548697 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ae34f82b-36bc-4e36-bc13-9dcc1f273e96","ELEMENT":"ae34f82b-36bc-4e36-bc13-9dcc1f273e96"}}]
07:05:48 INFO - 1500991548698 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"ae34f82b-36bc-4e36-bc13-9dcc1f273e96"}]
07:05:48 INFO - 1500991548941 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:05:48 INFO - 1500991548941 Marionette TRACE 376 <- [1,4,null,{}]
07:05:48 INFO - 1500991548943 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}]
07:05:48 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500991548946 Marionette TRACE 376 <- [1,5,null,{"value":null}]
07:05:48 INFO - 1500991548947 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
07:05:48 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 360ms
07:05:48 INFO - 1500991548949 Marionette TRACE 376 <- [1,6,null,{}]
07:05:48 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
07:05:48 INFO - 1500991548951 Marionette DEBUG Closed connection 376
07:05:48 INFO - 1500991548952 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50295
07:05:48 INFO - 1500991548953 Marionette DEBUG Closed connection 377
07:05:48 INFO - 1500991548954 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50296
07:05:48 INFO - 1500991548955 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:48 INFO - 1500991548956 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:48 INFO - 1500991548958 Marionette DEBUG Register listener.js for window 12
07:05:48 INFO - 1500991548961 Marionette TRACE 378 <- [1,1,null,{"sessionId":"d20f5ca2-7b23-429b-9f2f-201a70275d62","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:48 INFO - 1500991548963 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
07:05:48 INFO - 1500991548964 Marionette TRACE 378 <- [1,2,null,{}]
07:05:48 INFO - 1500991548965 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:05:48 INFO - 1500991548965 Marionette TRACE 378 <- [1,3,null,{}]
07:05:48 INFO - 1500991548966 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
07:05:48 INFO - 1500991548967 Marionette TRACE 378 <- [1,4,null,{}]
07:05:48 INFO - 1500991548969 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}]
07:05:48 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500991548972 Marionette TRACE 378 <- [1,5,null,{"value":null}]
07:05:48 INFO - 1500991548973 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
07:05:48 INFO - 1500991548974 Marionette TRACE 378 <- [1,6,null,{}]
07:05:48 INFO - 1500991548976 Marionette DEBUG Closed connection 378
07:05:48 INFO - 1500991548977 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50297
07:05:48 INFO - 1500991548978 Marionette DEBUG Closed connection 379
07:05:48 INFO - 1500991548979 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50298
07:05:48 INFO - 1500991548980 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:48 INFO - 1500991548980 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:49 INFO - 1500991548982 Marionette DEBUG Register listener.js for window 12
07:05:49 INFO - 1500991548986 Marionette TRACE 380 <- [1,1,null,{"sessionId":"1b0a8fec-30a0-4d5d-a999-02567d88503c","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:49 INFO - 1500991548988 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
07:05:49 INFO - 1500991548990 Marionette TRACE 380 <- [1,2,null,{}]
07:05:49 INFO - 1500991548992 Marionette DEBUG Closed connection 380
07:05:49 INFO - 1500991548993 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50299
07:05:49 INFO - 1500991548994 Marionette DEBUG Closed connection 381
07:05:49 INFO - 1500991548994 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50300
07:05:49 INFO - 1500991548995 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:05:49 INFO - 1500991548996 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:49 INFO - 1500991548998 Marionette DEBUG Register listener.js for window 12
07:05:49 INFO - 1500991549002 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d88fd9f1-2dc8-4cda-a2d1-1f347ee0bc00","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:49 INFO - 1500991549004 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"}]
07:05:49 INFO - 1500991549006 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"
07:05:49 INFO - 1500991549008 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"
07:05:49 INFO - 1500991549008 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"
07:05:49 INFO - 1500991549029 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"
07:05:49 INFO - 1500991549032 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"
07:05:49 INFO - 1500991549043 Marionette TRACE 382 <- [1,2,null,{}]
07:05:49 INFO - 1500991549045 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
07:05:49 INFO - 1500991549048 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eb72b706-a227-47c7-85fd-4891af6c5256","ELEMENT":"eb72b706-a227-47c7-85fd-4891af6c5256"}}]
07:05:49 INFO - 1500991549049 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"eb72b706-a227-47c7-85fd-4891af6c5256"}]
07:05:49 INFO - 1500991549295 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:05:49 INFO - 1500991549295 Marionette TRACE 382 <- [1,4,null,{}]
07:05:49 INFO - 1500991549297 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}]
07:05:49 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 353ms
07:05:49 INFO - TEST-START | test_click.py TestClick.test_input_file
07:05:49 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500991549300 Marionette TRACE 382 <- [1,5,null,{"value":null}]
07:05:49 INFO - 1500991549301 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
07:05:49 INFO - 1500991549303 Marionette TRACE 382 <- [1,6,null,{}]
07:05:49 INFO - 1500991549305 Marionette DEBUG Closed connection 382
07:05:49 INFO - 1500991549306 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50301
07:05:49 INFO - 1500991549307 Marionette DEBUG Closed connection 383
07:05:49 INFO - 1500991549308 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50302
07:05:49 INFO - 1500991549309 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:49 INFO - 1500991549310 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:49 INFO - 1500991549312 Marionette DEBUG Register listener.js for window 12
07:05:49 INFO - 1500991549316 Marionette TRACE 384 <- [1,1,null,{"sessionId":"d827c55c-0975-45c7-a06a-380a02cb6f92","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:49 INFO - 1500991549318 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
07:05:49 INFO - 1500991549318 Marionette TRACE 384 <- [1,2,null,{}]
07:05:49 INFO - 1500991549319 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:05:49 INFO - 1500991549320 Marionette TRACE 384 <- [1,3,null,{}]
07:05:49 INFO - 1500991549321 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
07:05:49 INFO - 1500991549321 Marionette TRACE 384 <- [1,4,null,{}]
07:05:49 INFO - 1500991549323 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}]
07:05:49 INFO - TEST-START: test_click.py TestClick.test_input_file1500991549326 Marionette TRACE 384 <- [1,5,null,{"value":null}]
07:05:49 INFO - 1500991549327 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
07:05:49 INFO - 1500991549328 Marionette TRACE 384 <- [1,6,null,{}]
07:05:49 INFO - 1500991549330 Marionette DEBUG Closed connection 384
07:05:49 INFO - 1500991549331 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50303
07:05:49 INFO - 1500991549332 Marionette DEBUG Closed connection 385
07:05:49 INFO - 1500991549333 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50304
07:05:49 INFO - 1500991549334 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:49 INFO - 1500991549342 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:49 INFO - 1500991549344 Marionette DEBUG Register listener.js for window 12
07:05:49 INFO - 1500991549348 Marionette TRACE 386 <- [1,1,null,{"sessionId":"5fe1f4e5-7a0e-4916-b050-130b38672843","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:49 INFO - 1500991549350 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
07:05:49 INFO - 1500991549352 Marionette TRACE 386 <- [1,2,null,{}]
07:05:49 INFO - 1500991549354 Marionette DEBUG Closed connection 386
07:05:49 INFO - 1500991549355 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50305
07:05:49 INFO - 1500991549356 Marionette DEBUG Closed connection 387
07:05:49 INFO - 1500991549357 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50306
07:05:49 INFO - 1500991549358 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:05:49 INFO - 1500991549358 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:49 INFO - 1500991549360 Marionette DEBUG Register listener.js for window 12
07:05:49 INFO - 1500991549364 Marionette TRACE 388 <- [1,1,null,{"sessionId":"ac3afdb5-8d38-416b-9f7e-24c4eea6d49b","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:49 INFO - 1500991549366 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
07:05:49 INFO - 1500991549368 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"
07:05:49 INFO - 1500991549370 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"
07:05:49 INFO - 1500991549371 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"
07:05:49 INFO - 1500991549393 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:05:49 INFO - 1500991549394 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:05:49 INFO - 1500991549399 Marionette TRACE 388 <- [1,2,null,{}]
07:05:49 INFO - 1500991549407 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
07:05:49 INFO - 1500991549410 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"45575822-6359-4de5-bbf5-e679ce9c89b9","ELEMENT":"45575822-6359-4de5-bbf5-e679ce9c89b9"}}]
07:05:49 INFO - 1500991549411 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"45575822-6359-4de5-bbf5-e679ce9c89b9"}]
07:05:49 INFO - 1500991549440 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
07:05:49 INFO - 1500991549442 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}]
07:05:49 INFO - TEST-END: test_click.py TestClick.test_input_file1500991549445 Marionette TRACE 388 <- [1,5,null,{"value":null}]
07:05:49 INFO - 1500991549446 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
07:05:49 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 144ms
07:05:49 INFO - TEST-START | test_click.py TestClick.test_obscured_element
07:05:49 INFO - 1500991549448 Marionette TRACE 388 <- [1,6,null,{}]
07:05:49 INFO - 1500991549450 Marionette DEBUG Closed connection 388
07:05:49 INFO - 1500991549451 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50307
07:05:49 INFO - 1500991549452 Marionette DEBUG Closed connection 389
07:05:49 INFO - 1500991549453 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50308
07:05:49 INFO - 1500991549454 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:49 INFO - 1500991549455 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:49 INFO - 1500991549457 Marionette DEBUG Register listener.js for window 12
07:05:49 INFO - 1500991549460 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d0945ee4-c9a5-40cc-a197-3d7c1bd0c0f4","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:49 INFO - 1500991549463 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
07:05:49 INFO - 1500991549463 Marionette TRACE 390 <- [1,2,null,{}]
07:05:49 INFO - 1500991549464 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:05:49 INFO - 1500991549465 Marionette TRACE 390 <- [1,3,null,{}]
07:05:49 INFO - 1500991549465 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
07:05:49 INFO - 1500991549466 Marionette TRACE 390 <- [1,4,null,{}]
07:05:49 INFO - 1500991549468 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}]
07:05:49 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500991549471 Marionette TRACE 390 <- [1,5,null,{"value":null}]
07:05:49 INFO - 1500991549472 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
07:05:49 INFO - 1500991549473 Marionette TRACE 390 <- [1,6,null,{}]
07:05:49 INFO - 1500991549476 Marionette DEBUG Closed connection 390
07:05:49 INFO - 1500991549476 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50309
07:05:49 INFO - 1500991549477 Marionette DEBUG Closed connection 391
07:05:49 INFO - 1500991549478 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50310
07:05:49 INFO - 1500991549479 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:49 INFO - 1500991549479 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:49 INFO - 1500991549482 Marionette DEBUG Register listener.js for window 12
07:05:49 INFO - 1500991549485 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ef874bce-6a4e-4d09-b15f-9261b9f8f6e6","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:49 INFO - 1500991549577 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
07:05:49 INFO - 1500991549581 Marionette TRACE 392 <- [1,2,null,{}]
07:05:49 INFO - 1500991549586 Marionette DEBUG Closed connection 392
07:05:49 INFO - 1500991549588 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50311
07:05:49 INFO - 1500991549589 Marionette DEBUG Closed connection 393
07:05:49 INFO - 1500991549590 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50312
07:05:49 INFO - 1500991549592 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:05:49 INFO - 1500991549596 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:49 INFO - 1500991549599 Marionette DEBUG Register listener.js for window 12
07:05:49 INFO - 1500991549607 Marionette TRACE 394 <- [1,1,null,{"sessionId":"76b99477-7af4-4c11-9bb1-f6e6b7487d6e","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:49 INFO - 1500991549660 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"}]
07:05:49 INFO - 1500991549665 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:05:49 INFO - 1500991549672 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:05:49 INFO - 1500991549673 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:05:49 INFO - 1500991549706 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"
07:05:49 INFO - 1500991549709 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"
07:05:49 INFO - 1500991549715 Marionette TRACE 394 <- [1,2,null,{}]
07:05:49 INFO - 1500991549728 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
07:05:49 INFO - 1500991549734 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"916950e1-9917-4227-a7b3-5e266864206f","ELEMENT":"916950e1-9917-4227-a7b3-5e266864206f"}}]
07:05:49 INFO - 1500991549736 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
07:05:49 INFO - 1500991549740 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d91179df-042c-4ca1-b90b-09770ff4f9e8","ELEMENT":"d91179df-042c-4ca1-b90b-09770ff4f9e8"}}]
07:05:49 INFO - 1500991549741 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"916950e1-9917-4227-a7b3-5e266864206f"}]
07:05:49 INFO - 1500991549989 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:05:49 INFO - 1500991549990 Marionette TRACE 394 <- [1,5,null,{}]
07:05:49 INFO - 1500991549991 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d91179df-042c-4ca1-b90b-09770ff4f9e8"}]
07:05:50 INFO - 1500991550012 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
07:05:50 INFO - 1500991550014 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
07:05:50 INFO - 1500991550019 Marionette TRACE 394 <- [1,7,null,{"value":false}]
07:05:50 INFO - 1500991550021 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}]
07:05:50 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500991550025 Marionette TRACE 394 <- [1,8,null,{"value":null}]
07:05:50 INFO - 1500991550027 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
07:05:50 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 580ms
07:05:50 INFO - 1500991550029 Marionette TRACE 394 <- [1,9,null,{}]
07:05:50 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
07:05:50 INFO - 1500991550032 Marionette DEBUG Closed connection 394
07:05:50 INFO - 1500991550034 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50313
07:05:50 INFO - 1500991550035 Marionette DEBUG Closed connection 395
07:05:50 INFO - 1500991550036 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50314
07:05:50 INFO - 1500991550039 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:50 INFO - 1500991550040 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:50 INFO - 1500991550042 Marionette DEBUG Register listener.js for window 12
07:05:50 INFO - 1500991550048 Marionette TRACE 396 <- [1,1,null,{"sessionId":"45148180-144b-4aed-bf3c-be6794351e8a","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:50 INFO - 1500991550051 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
07:05:50 INFO - 1500991550052 Marionette TRACE 396 <- [1,2,null,{}]
07:05:50 INFO - 1500991550053 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:05:50 INFO - 1500991550054 Marionette TRACE 396 <- [1,3,null,{}]
07:05:50 INFO - 1500991550055 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
07:05:50 INFO - 1500991550055 Marionette TRACE 396 <- [1,4,null,{}]
07:05:50 INFO - 1500991550057 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}]
07:05:50 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500991550061 Marionette TRACE 396 <- [1,5,null,{"value":null}]
07:05:50 INFO - 1500991550062 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
07:05:50 INFO - 1500991550064 Marionette TRACE 396 <- [1,6,null,{}]
07:05:50 INFO - 1500991550066 Marionette DEBUG Closed connection 396
07:05:50 INFO - 1500991550067 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50315
07:05:50 INFO - 1500991550068 Marionette DEBUG Closed connection 397
07:05:50 INFO - 1500991550069 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50316
07:05:50 INFO - 1500991550070 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:05:50 INFO - 1500991550071 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:50 INFO - 1500991550074 Marionette DEBUG Register listener.js for window 12
07:05:50 INFO - 1500991550079 Marionette TRACE 398 <- [1,1,null,{"sessionId":"79f221cc-1967-41bd-b725-82bce7101cef","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:50 INFO - 1500991550099 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
07:05:50 INFO - 1500991550100 Marionette TRACE 398 <- [1,2,null,{}]
07:05:50 INFO - 1500991550106 Marionette DEBUG Closed connection 398
07:05:50 INFO - 1500991550107 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50317
07:05:50 INFO - 1500991550108 Marionette DEBUG Closed connection 399
07:05:50 INFO - 1500991550109 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50318
07:05:50 INFO - 1500991550144 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:05:50 INFO - 1500991550145 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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}
07:05:50 INFO - 1500991550148 Marionette DEBUG Register listener.js for window 12
07:05:50 INFO - 1500991550154 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a67b94a3-4850-42af-9f40-e3f48cd813ad","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":1588,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprqj9_p.mozrunner","moz:accessibilityChecks":false}}]
07:05:50 INFO - 1500991550157 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"}]
07:05:50 INFO - 1500991550159 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"
07:05:50 INFO - 1500991550163 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"
07:05:50 INFO - 1500991550163 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"
07:05:50 INFO - 1500991550190 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"
07:05:50 INFO - 1500991550192 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"
07:05:50 INFO - 1500991550198 Marionette TRACE 400 <- [1,2,null,{}]
07:05:50 INFO - 1500991550199 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
07:05:50 INFO - 1500991550210 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b1c8d807-023c-41c7-9717-b38d0afab74f","ELEMENT":"b1c8d807-023c-41c7-9717-b38d0afab74f"}}]
07:05:50 INFO - 1500991550211 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b1c8d807-023c-41c7-9717-b38d0afab74f"}]
07:05:50 INFO - 1500991550238 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
07:05:50 INFO - 1500991550244 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b1c8d807-023c-41c7-9717-b38d0afab74f"}]
07:05:50 INFO - 1500991550263 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element