could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:23:25 INFO - 1499109805644 Marionette TRACE 353 -> [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}]
12:23:25 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499109805646 Marionette TRACE 353 <- [1,5,null,{"value":null}]
12:23:25 INFO - 1499109805647 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
12:23:25 INFO - 1499109805649 Marionette TRACE 353 <- [1,6,null,{}]
12:23:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 132ms
12:23:25 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:23:25 INFO - 1499109805650 Marionette DEBUG Closed connection 353
12:23:25 INFO - 1499109805651 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50350
12:23:25 INFO - 1499109805652 Marionette DEBUG Closed connection 354
12:23:25 INFO - 1499109805653 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50351
12:23:25 INFO - 1499109805654 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:25 INFO - 1499109805655 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:25 INFO - 1499109805657 Marionette DEBUG Register listener.js for window 12
12:23:25 INFO - 1499109805660 Marionette TRACE 355 <- [1,1,null,{"sessionId":"41e6fe9b-229e-44c5-b8cb-eec6ada19c40","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:25 INFO - 1499109805662 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
12:23:25 INFO - 1499109805663 Marionette TRACE 355 <- [1,2,null,{}]
12:23:25 INFO - 1499109805664 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:25 INFO - 1499109805664 Marionette TRACE 355 <- [1,3,null,{}]
12:23:25 INFO - 1499109805665 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:25 INFO - 1499109805665 Marionette TRACE 355 <- [1,4,null,{}]
12:23:25 INFO - 1499109805667 Marionette TRACE 355 -> [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}]
12:23:25 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499109805670 Marionette TRACE 355 <- [1,5,null,{"value":null}]
12:23:25 INFO - 1499109805671 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
12:23:25 INFO - 1499109805672 Marionette TRACE 355 <- [1,6,null,{}]
12:23:25 INFO - 1499109805674 Marionette DEBUG Closed connection 355
12:23:25 INFO - 1499109805674 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50352
12:23:25 INFO - 1499109805675 Marionette DEBUG Closed connection 356
12:23:25 INFO - 1499109805676 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50353
12:23:25 INFO - 1499109805677 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:25 INFO - 1499109805679 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:25 INFO - 1499109805681 Marionette DEBUG Register listener.js for window 12
12:23:25 INFO - 1499109805684 Marionette TRACE 357 <- [1,1,null,{"sessionId":"28d64e23-2b3f-4729-8f73-701893802f3d","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:25 INFO - 1499109805685 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
12:23:25 INFO - 1499109805686 Marionette TRACE 357 <- [1,2,null,{}]
12:23:25 INFO - 1499109805688 Marionette DEBUG Closed connection 357
12:23:25 INFO - 1499109805688 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50354
12:23:25 INFO - 1499109805689 Marionette DEBUG Closed connection 358
12:23:25 INFO - 1499109805690 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50355
12:23:25 INFO - 1499109805691 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:25 INFO - 1499109805692 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:25 INFO - 1499109805694 Marionette DEBUG Register listener.js for window 12
12:23:25 INFO - 1499109805696 Marionette TRACE 359 <- [1,1,null,{"sessionId":"3d29d21f-1ec7-423e-a67b-8dc9440f7ee5","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:25 INFO - 1499109805698 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49921/clicks.html"}]
12:23:25 INFO - 1499109805699 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49921/hidden.html"
12:23:25 INFO - 1499109805704 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49921/hidden.html"
12:23:25 INFO - 1499109805705 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49921/hidden.html"
12:23:25 INFO - 1499109805722 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49921/clicks.html"
12:23:25 INFO - 1499109805729 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49921/clicks.html"
12:23:25 INFO - 1499109805732 Marionette TRACE 359 <- [1,2,null,{}]
12:23:25 INFO - 1499109805734 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:23:25 INFO - 1499109805736 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"318b6cf8-a317-4ffa-9326-2a276bb80745","ELEMENT":"318b6cf8-a317-4ffa-9326-2a276bb80745"}}]
12:23:25 INFO - 1499109805744 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"318b6cf8-a317-4ffa-9326-2a276bb80745"}]
12:23:25 INFO - 1499109805793 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49921/clicks.html"
12:23:25 INFO - 1499109805801 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49921/clicks.html"
12:23:25 INFO - 1499109805801 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49921/clicks.html"
12:23:25 INFO - 1499109805816 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49921/test.html"
12:23:25 INFO - 1499109805820 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49921/test.html"
12:23:25 INFO - 1499109805823 Marionette TRACE 359 <- [1,4,null,{}]
12:23:25 INFO - 1499109805832 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:23:25 INFO - 1499109805836 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"be39e4b7-e81d-489b-b392-72ae879d9ad3","ELEMENT":"be39e4b7-e81d-489b-b392-72ae879d9ad3"}}]
12:23:25 INFO - 1499109805837 Marionette TRACE 359 -> [0,6,"getTitle",{}]
12:23:25 INFO - 1499109805839 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
12:23:25 INFO - 1499109805841 Marionette TRACE 359 -> [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}]
12:23:25 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499109805844 Marionette TRACE 359 <- [1,7,null,{"value":null}]
12:23:25 INFO - 1499109805845 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
12:23:25 INFO - 1499109805846 Marionette TRACE 359 <- [1,8,null,{}]
12:23:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 197ms
12:23:25 INFO - TEST-START | test_click.py TestClick.test_container_element
12:23:25 INFO - 1499109805848 Marionette DEBUG Closed connection 359
12:23:25 INFO - 1499109805849 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50358
12:23:25 INFO - 1499109805850 Marionette DEBUG Closed connection 360
12:23:25 INFO - 1499109805851 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50359
12:23:25 INFO - 1499109805852 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:25 INFO - 1499109805852 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:25 INFO - 1499109805854 Marionette DEBUG Register listener.js for window 12
12:23:25 INFO - 1499109805858 Marionette TRACE 361 <- [1,1,null,{"sessionId":"4c70544a-521c-48fb-98d1-dfb30bc3dfda","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:25 INFO - 1499109805859 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
12:23:25 INFO - 1499109805859 Marionette TRACE 361 <- [1,2,null,{}]
12:23:25 INFO - 1499109805860 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:25 INFO - 1499109805860 Marionette TRACE 361 <- [1,3,null,{}]
12:23:25 INFO - 1499109805861 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:25 INFO - 1499109805861 Marionette TRACE 361 <- [1,4,null,{}]
12:23:25 INFO - 1499109805863 Marionette TRACE 361 -> [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}]
12:23:25 INFO - TEST-START: test_click.py TestClick.test_container_element1499109805866 Marionette TRACE 361 <- [1,5,null,{"value":null}]
12:23:25 INFO - 1499109805867 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
12:23:25 INFO - 1499109805868 Marionette TRACE 361 <- [1,6,null,{}]
12:23:25 INFO - 1499109805870 Marionette DEBUG Closed connection 361
12:23:25 INFO - 1499109805870 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50360
12:23:25 INFO - 1499109805871 Marionette DEBUG Closed connection 362
12:23:25 INFO - 1499109805872 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50361
12:23:25 INFO - 1499109805873 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:25 INFO - 1499109805874 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:25 INFO - 1499109805876 Marionette DEBUG Register listener.js for window 12
12:23:25 INFO - 1499109805879 Marionette TRACE 363 <- [1,1,null,{"sessionId":"e613fd59-ab41-47ea-860a-d17d66d6eb00","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:25 INFO - 1499109805880 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
12:23:25 INFO - 1499109805881 Marionette TRACE 363 <- [1,2,null,{}]
12:23:25 INFO - 1499109805882 Marionette DEBUG Closed connection 363
12:23:25 INFO - 1499109805883 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50362
12:23:25 INFO - 1499109805884 Marionette DEBUG Closed connection 364
12:23:25 INFO - 1499109805885 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50363
12:23:25 INFO - 1499109805886 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:25 INFO - 1499109805886 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:25 INFO - 1499109805888 Marionette DEBUG Register listener.js for window 12
12:23:25 INFO - 1499109805891 Marionette TRACE 365 <- [1,1,null,{"sessionId":"583ecc7c-76d5-434e-9ccf-36ffdb20c914","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:25 INFO - 1499109805892 Marionette TRACE 365 -> [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"}]
12:23:25 INFO - 1499109805894 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49921/test.html"
12:23:25 INFO - 1499109805897 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49921/test.html"
12:23:25 INFO - 1499109805897 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49921/test.html"
12:23:25 INFO - 1499109805929 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"
12:23:25 INFO - 1499109805932 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"
12:23:25 INFO - 1499109805938 Marionette TRACE 365 <- [1,2,null,{}]
12:23:25 INFO - 1499109805946 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:23:25 INFO - 1499109805949 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3694f0b5-60a8-41c7-8dd0-70c6e8d012fe","ELEMENT":"3694f0b5-60a8-41c7-8dd0-70c6e8d012fe"}}]
12:23:25 INFO - 1499109805953 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"3694f0b5-60a8-41c7-8dd0-70c6e8d012fe"}]
12:23:26 INFO - 1499109806192 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:26 INFO - 1499109806193 Marionette TRACE 365 <- [1,4,null,{}]
12:23:26 INFO - 1499109806194 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"3694f0b5-60a8-41c7-8dd0-70c6e8d012fe","name":"selected"}]
12:23:26 INFO - 1499109806198 Marionette TRACE 365 <- [1,5,null,{"value":true}]
12:23:26 INFO - 1499109806200 Marionette TRACE 365 -> [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}]
12:23:26 INFO - TEST-END: test_click.py TestClick.test_container_element1499109806202 Marionette TRACE 365 <- [1,6,null,{"value":null}]
12:23:26 INFO - 1499109806203 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
12:23:26 INFO - 1499109806205 Marionette TRACE 365 <- [1,7,null,{}]
12:23:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 358ms
12:23:26 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:23:26 INFO - 1499109806206 Marionette DEBUG Closed connection 365
12:23:26 INFO - 1499109806208 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50364
12:23:26 INFO - 1499109806208 Marionette DEBUG Closed connection 366
12:23:26 INFO - 1499109806209 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50365
12:23:26 INFO - 1499109806210 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:26 INFO - 1499109806211 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806213 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806216 Marionette TRACE 367 <- [1,1,null,{"sessionId":"cb51a5a7-5a90-4b1b-be3e-72cfc48b3e84","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806217 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
12:23:26 INFO - 1499109806218 Marionette TRACE 367 <- [1,2,null,{}]
12:23:26 INFO - 1499109806218 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:26 INFO - 1499109806219 Marionette TRACE 367 <- [1,3,null,{}]
12:23:26 INFO - 1499109806220 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:26 INFO - 1499109806220 Marionette TRACE 367 <- [1,4,null,{}]
12:23:26 INFO - 1499109806222 Marionette TRACE 367 -> [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}]
12:23:26 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499109806225 Marionette TRACE 367 <- [1,5,null,{"value":null}]
12:23:26 INFO - 1499109806226 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
12:23:26 INFO - 1499109806227 Marionette TRACE 367 <- [1,6,null,{}]
12:23:26 INFO - 1499109806229 Marionette DEBUG Closed connection 367
12:23:26 INFO - 1499109806229 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50366
12:23:26 INFO - 1499109806230 Marionette DEBUG Closed connection 368
12:23:26 INFO - 1499109806231 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50367
12:23:26 INFO - 1499109806232 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:26 INFO - 1499109806233 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806235 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806238 Marionette TRACE 369 <- [1,1,null,{"sessionId":"29e4e8d4-aa72-49f7-97a8-5487f779711d","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806239 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
12:23:26 INFO - 1499109806240 Marionette TRACE 369 <- [1,2,null,{}]
12:23:26 INFO - 1499109806242 Marionette DEBUG Closed connection 369
12:23:26 INFO - 1499109806242 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50368
12:23:26 INFO - 1499109806243 Marionette DEBUG Closed connection 370
12:23:26 INFO - 1499109806244 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50369
12:23:26 INFO - 1499109806245 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:26 INFO - 1499109806245 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806247 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806251 Marionette TRACE 371 <- [1,1,null,{"sessionId":"23938969-3494-4889-bf6b-963c28171caf","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806252 Marionette TRACE 371 -> [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"}]
12:23:26 INFO - 1499109806253 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"
12:23:26 INFO - 1499109806255 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"
12:23:26 INFO - 1499109806255 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"
12:23:26 INFO - 1499109806269 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"
12:23:26 INFO - 1499109806273 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"
12:23:26 INFO - 1499109806282 Marionette TRACE 371 <- [1,2,null,{}]
12:23:26 INFO - 1499109806305 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:23:26 INFO - 1499109806307 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d31e7d78-d2c6-443d-920a-f7fdea7107e2","ELEMENT":"d31e7d78-d2c6-443d-920a-f7fdea7107e2"}}]
12:23:26 INFO - 1499109806308 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"d31e7d78-d2c6-443d-920a-f7fdea7107e2"}]
12:23:26 INFO - 1499109806561 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:26 INFO - 1499109806562 Marionette TRACE 371 <- [1,4,null,{}]
12:23:26 INFO - 1499109806563 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"d31e7d78-d2c6-443d-920a-f7fdea7107e2","name":"selected"}]
12:23:26 INFO - 1499109806567 Marionette TRACE 371 <- [1,5,null,{"value":true}]
12:23:26 INFO - 1499109806569 Marionette TRACE 371 -> [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}]
12:23:26 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499109806571 Marionette TRACE 371 <- [1,6,null,{"value":null}]
12:23:26 INFO - 1499109806572 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
12:23:26 INFO - 1499109806573 Marionette TRACE 371 <- [1,7,null,{}]
12:23:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 368ms
12:23:26 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:23:26 INFO - 1499109806575 Marionette DEBUG Closed connection 371
12:23:26 INFO - 1499109806576 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50370
12:23:26 INFO - 1499109806577 Marionette DEBUG Closed connection 372
12:23:26 INFO - 1499109806578 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50371
12:23:26 INFO - 1499109806579 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:26 INFO - 1499109806579 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806581 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806584 Marionette TRACE 373 <- [1,1,null,{"sessionId":"49357566-0971-4c20-aacb-8a5f86a284a2","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806585 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
12:23:26 INFO - 1499109806586 Marionette TRACE 373 <- [1,2,null,{}]
12:23:26 INFO - 1499109806587 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:26 INFO - 1499109806587 Marionette TRACE 373 <- [1,3,null,{}]
12:23:26 INFO - 1499109806588 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:26 INFO - 1499109806588 Marionette TRACE 373 <- [1,4,null,{}]
12:23:26 INFO - 1499109806590 Marionette TRACE 373 -> [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}]
12:23:26 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499109806593 Marionette TRACE 373 <- [1,5,null,{"value":null}]
12:23:26 INFO - 1499109806593 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
12:23:26 INFO - 1499109806594 Marionette TRACE 373 <- [1,6,null,{}]
12:23:26 INFO - 1499109806596 Marionette DEBUG Closed connection 373
12:23:26 INFO - 1499109806597 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50372
12:23:26 INFO - 1499109806598 Marionette DEBUG Closed connection 374
12:23:26 INFO - 1499109806598 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50373
12:23:26 INFO - 1499109806600 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:26 INFO - 1499109806600 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806602 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806605 Marionette TRACE 375 <- [1,1,null,{"sessionId":"e8431322-77b4-42ab-a0a9-e50b40b10ad2","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806606 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
12:23:26 INFO - 1499109806607 Marionette TRACE 375 <- [1,2,null,{}]
12:23:26 INFO - 1499109806609 Marionette DEBUG Closed connection 375
12:23:26 INFO - 1499109806610 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50374
12:23:26 INFO - 1499109806610 Marionette DEBUG Closed connection 376
12:23:26 INFO - 1499109806611 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50375
12:23:26 INFO - 1499109806612 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:26 INFO - 1499109806613 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806615 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806618 Marionette TRACE 377 <- [1,1,null,{"sessionId":"0f7ea43c-85fc-468f-b2af-811bb398abb7","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806619 Marionette TRACE 377 -> [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"}]
12:23:26 INFO - 1499109806621 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"
12:23:26 INFO - 1499109806623 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"
12:23:26 INFO - 1499109806624 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"
12:23:26 INFO - 1499109806635 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"
12:23:26 INFO - 1499109806639 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"
12:23:26 INFO - 1499109806652 Marionette TRACE 377 <- [1,2,null,{}]
12:23:26 INFO - 1499109806654 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:23:26 INFO - 1499109806657 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4396a9d9-7315-46bd-87e8-8b5b4848adbb","ELEMENT":"4396a9d9-7315-46bd-87e8-8b5b4848adbb"}}]
12:23:26 INFO - 1499109806658 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"4396a9d9-7315-46bd-87e8-8b5b4848adbb"}]
12:23:26 INFO - 1499109806893 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:26 INFO - 1499109806894 Marionette TRACE 377 <- [1,4,null,{}]
12:23:26 INFO - 1499109806896 Marionette TRACE 377 -> [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}]
12:23:26 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499109806898 Marionette TRACE 377 <- [1,5,null,{"value":null}]
12:23:26 INFO - 1499109806899 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
12:23:26 INFO - 1499109806900 Marionette TRACE 377 <- [1,6,null,{}]
12:23:26 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 327ms
12:23:26 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:23:26 INFO - 1499109806902 Marionette DEBUG Closed connection 377
12:23:26 INFO - 1499109806903 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50376
12:23:26 INFO - 1499109806904 Marionette DEBUG Closed connection 378
12:23:26 INFO - 1499109806905 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50377
12:23:26 INFO - 1499109806906 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:26 INFO - 1499109806906 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806909 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806912 Marionette TRACE 379 <- [1,1,null,{"sessionId":"b1f40147-522d-42ed-8a53-acfe9683025a","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806913 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
12:23:26 INFO - 1499109806913 Marionette TRACE 379 <- [1,2,null,{}]
12:23:26 INFO - 1499109806914 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:26 INFO - 1499109806914 Marionette TRACE 379 <- [1,3,null,{}]
12:23:26 INFO - 1499109806915 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:26 INFO - 1499109806916 Marionette TRACE 379 <- [1,4,null,{}]
12:23:26 INFO - 1499109806917 Marionette TRACE 379 -> [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}]
12:23:26 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499109806920 Marionette TRACE 379 <- [1,5,null,{"value":null}]
12:23:26 INFO - 1499109806921 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
12:23:26 INFO - 1499109806922 Marionette TRACE 379 <- [1,6,null,{}]
12:23:26 INFO - 1499109806924 Marionette DEBUG Closed connection 379
12:23:26 INFO - 1499109806925 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50378
12:23:26 INFO - 1499109806925 Marionette DEBUG Closed connection 380
12:23:26 INFO - 1499109806926 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50379
12:23:26 INFO - 1499109806927 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:26 INFO - 1499109806928 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806930 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806933 Marionette TRACE 381 <- [1,1,null,{"sessionId":"5b9110ff-f986-4ca8-a9f0-80edb549a4a5","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806934 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
12:23:26 INFO - 1499109806935 Marionette TRACE 381 <- [1,2,null,{}]
12:23:26 INFO - 1499109806936 Marionette DEBUG Closed connection 381
12:23:26 INFO - 1499109806937 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50380
12:23:26 INFO - 1499109806938 Marionette DEBUG Closed connection 382
12:23:26 INFO - 1499109806938 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50381
12:23:26 INFO - 1499109806939 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:26 INFO - 1499109806940 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:26 INFO - 1499109806942 Marionette DEBUG Register listener.js for window 12
12:23:26 INFO - 1499109806945 Marionette TRACE 383 <- [1,1,null,{"sessionId":"f11a0b16-cb02-4b93-99fe-0b9d4b260e11","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:26 INFO - 1499109806946 Marionette TRACE 383 -> [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"}]
12:23:26 INFO - 1499109806947 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"
12:23:26 INFO - 1499109806949 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"
12:23:26 INFO - 1499109806950 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"
12:23:26 INFO - 1499109806965 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"
12:23:26 INFO - 1499109806968 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"
12:23:26 INFO - 1499109806971 Marionette TRACE 383 <- [1,2,null,{}]
12:23:26 INFO - 1499109806981 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:23:26 INFO - 1499109806983 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e456f6a0-9887-40db-8d4b-097ddd724f15","ELEMENT":"e456f6a0-9887-40db-8d4b-097ddd724f15"}}]
12:23:26 INFO - 1499109806984 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"e456f6a0-9887-40db-8d4b-097ddd724f15"}]
12:23:27 INFO - 1499109807232 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:27 INFO - 1499109807233 Marionette TRACE 383 <- [1,4,null,{}]
12:23:27 INFO - 1499109807235 Marionette TRACE 383 -> [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}]
12:23:27 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499109807237 Marionette TRACE 383 <- [1,5,null,{"value":null}]
12:23:27 INFO - 1499109807238 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
12:23:27 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 340ms
12:23:27 INFO - TEST-START | test_click.py TestClick.test_input_file
12:23:27 INFO - 1499109807240 Marionette TRACE 383 <- [1,6,null,{}]
12:23:27 INFO - 1499109807241 Marionette DEBUG Closed connection 383
12:23:27 INFO - 1499109807243 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50382
12:23:27 INFO - 1499109807243 Marionette DEBUG Closed connection 384
12:23:27 INFO - 1499109807244 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50383
12:23:27 INFO - 1499109807245 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:27 INFO - 1499109807246 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807248 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807251 Marionette TRACE 385 <- [1,1,null,{"sessionId":"7515b5f3-8cf2-479f-b9db-d7a3ba8b3a6d","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807252 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
12:23:27 INFO - 1499109807252 Marionette TRACE 385 <- [1,2,null,{}]
12:23:27 INFO - 1499109807253 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:27 INFO - 1499109807253 Marionette TRACE 385 <- [1,3,null,{}]
12:23:27 INFO - 1499109807254 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:27 INFO - 1499109807255 Marionette TRACE 385 <- [1,4,null,{}]
12:23:27 INFO - 1499109807256 Marionette TRACE 385 -> [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}]
12:23:27 INFO - TEST-START: test_click.py TestClick.test_input_file1499109807259 Marionette TRACE 385 <- [1,5,null,{"value":null}]
12:23:27 INFO - 1499109807260 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
12:23:27 INFO - 1499109807261 Marionette TRACE 385 <- [1,6,null,{}]
12:23:27 INFO - 1499109807263 Marionette DEBUG Closed connection 385
12:23:27 INFO - 1499109807263 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50384
12:23:27 INFO - 1499109807264 Marionette DEBUG Closed connection 386
12:23:27 INFO - 1499109807265 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50385
12:23:27 INFO - 1499109807266 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:27 INFO - 1499109807266 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807268 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807272 Marionette TRACE 387 <- [1,1,null,{"sessionId":"c9f0280b-4b5e-47af-bba6-4866be67c4a0","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807273 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
12:23:27 INFO - 1499109807274 Marionette TRACE 387 <- [1,2,null,{}]
12:23:27 INFO - 1499109807275 Marionette DEBUG Closed connection 387
12:23:27 INFO - 1499109807276 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50386
12:23:27 INFO - 1499109807277 Marionette DEBUG Closed connection 388
12:23:27 INFO - 1499109807278 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50387
12:23:27 INFO - 1499109807278 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:27 INFO - 1499109807279 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807281 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807284 Marionette TRACE 389 <- [1,1,null,{"sessionId":"b6854b31-fc43-4100-8523-07e9abdf2893","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807285 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:23:27 INFO - 1499109807287 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"
12:23:27 INFO - 1499109807289 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"
12:23:27 INFO - 1499109807289 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"
12:23:27 INFO - 1499109807308 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:27 INFO - 1499109807310 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:27 INFO - 1499109807315 Marionette TRACE 389 <- [1,2,null,{}]
12:23:27 INFO - 1499109807316 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:23:27 INFO - 1499109807319 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5288c9bf-5f39-48a1-aadd-ecf6734dc7c8","ELEMENT":"5288c9bf-5f39-48a1-aadd-ecf6734dc7c8"}}]
12:23:27 INFO - 1499109807320 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"5288c9bf-5f39-48a1-aadd-ecf6734dc7c8"}]
12:23:27 INFO - 1499109807352 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:23:27 INFO - 1499109807354 Marionette TRACE 389 -> [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}]
12:23:27 INFO - TEST-END: test_click.py TestClick.test_input_file1499109807360 Marionette TRACE 389 <- [1,5,null,{"value":null}]
12:23:27 INFO - 1499109807362 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
12:23:27 INFO - 1499109807363 Marionette TRACE 389 <- [1,6,null,{}]
12:23:27 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 123ms
12:23:27 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:23:27 INFO - 1499109807367 Marionette DEBUG Closed connection 389
12:23:27 INFO - 1499109807367 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50388
12:23:27 INFO - 1499109807368 Marionette DEBUG Closed connection 390
12:23:27 INFO - 1499109807369 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50389
12:23:27 INFO - 1499109807370 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:27 INFO - 1499109807371 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807373 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807376 Marionette TRACE 391 <- [1,1,null,{"sessionId":"c13f36a7-9ba4-44b5-be24-32434c527020","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807377 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
12:23:27 INFO - 1499109807377 Marionette TRACE 391 <- [1,2,null,{}]
12:23:27 INFO - 1499109807378 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:27 INFO - 1499109807379 Marionette TRACE 391 <- [1,3,null,{}]
12:23:27 INFO - 1499109807380 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:27 INFO - 1499109807380 Marionette TRACE 391 <- [1,4,null,{}]
12:23:27 INFO - 1499109807382 Marionette TRACE 391 -> [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}]
12:23:27 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499109807384 Marionette TRACE 391 <- [1,5,null,{"value":null}]
12:23:27 INFO - 1499109807385 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
12:23:27 INFO - 1499109807386 Marionette TRACE 391 <- [1,6,null,{}]
12:23:27 INFO - 1499109807388 Marionette DEBUG Closed connection 391
12:23:27 INFO - 1499109807389 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50390
12:23:27 INFO - 1499109807390 Marionette DEBUG Closed connection 392
12:23:27 INFO - 1499109807390 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50391
12:23:27 INFO - 1499109807391 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:27 INFO - 1499109807392 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807394 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807397 Marionette TRACE 393 <- [1,1,null,{"sessionId":"8d139ffc-3a6d-4375-8a2c-e0aa94800778","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807398 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
12:23:27 INFO - 1499109807399 Marionette TRACE 393 <- [1,2,null,{}]
12:23:27 INFO - 1499109807401 Marionette DEBUG Closed connection 393
12:23:27 INFO - 1499109807402 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50392
12:23:27 INFO - 1499109807402 Marionette DEBUG Closed connection 394
12:23:27 INFO - 1499109807403 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50393
12:23:27 INFO - 1499109807404 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:27 INFO - 1499109807405 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807407 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807410 Marionette TRACE 395 <- [1,1,null,{"sessionId":"a1fce26e-1838-4c25-bbba-dd72ffb9a678","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807412 Marionette TRACE 395 -> [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"}]
12:23:27 INFO - 1499109807414 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:27 INFO - 1499109807416 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:27 INFO - 1499109807416 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:27 INFO - 1499109807434 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"
12:23:27 INFO - 1499109807437 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"
12:23:27 INFO - 1499109807440 Marionette TRACE 395 <- [1,2,null,{}]
12:23:27 INFO - 1499109807448 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:23:27 INFO - 1499109807450 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b291d409-410f-4635-b55f-ede652e8992a","ELEMENT":"b291d409-410f-4635-b55f-ede652e8992a"}}]
12:23:27 INFO - 1499109807451 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:23:27 INFO - 1499109807453 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f8963ff2-4409-476c-89a1-540d13826b3c","ELEMENT":"f8963ff2-4409-476c-89a1-540d13826b3c"}}]
12:23:27 INFO - 1499109807454 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"b291d409-410f-4635-b55f-ede652e8992a"}]
12:23:27 INFO - 1499109807696 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:27 INFO - 1499109807697 Marionette TRACE 395 <- [1,5,null,{}]
12:23:27 INFO - 1499109807698 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"f8963ff2-4409-476c-89a1-540d13826b3c"}]
12:23:27 INFO - 1499109807712 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:23:27 INFO - 1499109807714 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:23:27 INFO - 1499109807716 Marionette TRACE 395 <- [1,7,null,{"value":false}]
12:23:27 INFO - 1499109807718 Marionette TRACE 395 -> [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}]
12:23:27 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499109807720 Marionette TRACE 395 <- [1,8,null,{"value":null}]
12:23:27 INFO - 1499109807721 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
12:23:27 INFO - 1499109807722 Marionette TRACE 395 <- [1,9,null,{}]
12:23:27 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 358ms
12:23:27 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:23:27 INFO - 1499109807724 Marionette DEBUG Closed connection 395
12:23:27 INFO - 1499109807725 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50394
12:23:27 INFO - 1499109807726 Marionette DEBUG Closed connection 396
12:23:27 INFO - 1499109807727 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50395
12:23:27 INFO - 1499109807728 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:27 INFO - 1499109807729 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807731 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807734 Marionette TRACE 397 <- [1,1,null,{"sessionId":"b7b6a801-45e8-4d9a-b2f3-19b7c6d77abd","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807735 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
12:23:27 INFO - 1499109807735 Marionette TRACE 397 <- [1,2,null,{}]
12:23:27 INFO - 1499109807736 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:27 INFO - 1499109807737 Marionette TRACE 397 <- [1,3,null,{}]
12:23:27 INFO - 1499109807738 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:27 INFO - 1499109807738 Marionette TRACE 397 <- [1,4,null,{}]
12:23:27 INFO - 1499109807740 Marionette TRACE 397 -> [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}]
12:23:27 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499109807742 Marionette TRACE 397 <- [1,5,null,{"value":null}]
12:23:27 INFO - 1499109807743 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
12:23:27 INFO - 1499109807744 Marionette TRACE 397 <- [1,6,null,{}]
12:23:27 INFO - 1499109807746 Marionette DEBUG Closed connection 397
12:23:27 INFO - 1499109807747 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50396
12:23:27 INFO - 1499109807747 Marionette DEBUG Closed connection 398
12:23:27 INFO - 1499109807748 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50397
12:23:27 INFO - 1499109807749 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:27 INFO - 1499109807750 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807752 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807754 Marionette TRACE 399 <- [1,1,null,{"sessionId":"342b295e-807e-460d-a931-bd1943265e21","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807756 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
12:23:27 INFO - 1499109807757 Marionette TRACE 399 <- [1,2,null,{}]
12:23:27 INFO - 1499109807758 Marionette DEBUG Closed connection 399
12:23:27 INFO - 1499109807759 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50398
12:23:27 INFO - 1499109807760 Marionette DEBUG Closed connection 400
12:23:27 INFO - 1499109807760 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50399
12:23:27 INFO - 1499109807762 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:27 INFO - 1499109807762 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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}
12:23:27 INFO - 1499109807764 Marionette DEBUG Register listener.js for window 12
12:23:27 INFO - 1499109807767 Marionette TRACE 401 <- [1,1,null,{"sessionId":"4e23587c-0d3b-48c7-b993-47db1612b8c0","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":1544,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpp483u8.mozrunner","moz:accessibilityChecks":false}}]
12:23:27 INFO - 1499109807768 Marionette TRACE 401 -> [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"}]
12:23:27 INFO - 1499109807769 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"
12:23:27 INFO - 1499109807771 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"
12:23:27 INFO - 1499109807772 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"
12:23:27 INFO - 1499109807788 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"
12:23:27 INFO - 1499109807791 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"
12:23:27 INFO - 1499109807800 Marionette TRACE 401 <- [1,2,null,{}]
12:23:27 INFO - 1499109807802 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:23:27 INFO - 1499109807805 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d7c166ce-d151-49c2-87dd-2ff8f8c3b34a","ELEMENT":"d7c166ce-d151-49c2-87dd-2ff8f8c3b34a"}}]
12:23:27 INFO - 1499109807807 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"d7c166ce-d151-49c2-87dd-2ff8f8c3b34a"}]
12:23:27 INFO - 1499109807828 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
12:23:27 INFO - 1499109807829 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"d7c166ce-d151-49c2-87dd-2ff8f8c3b34a"}]
12:23:27 INFO - 1499109807847 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element