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]
21:21:37 INFO - 1498969297877 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}]
21:21:37 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498969297879 Marionette TRACE 353 <- [1,5,null,{"value":null}]
21:21:37 INFO - 1498969297898 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
21:21:37 INFO - 1498969297900 Marionette TRACE 353 <- [1,6,null,{}]
21:21:37 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 160ms
21:21:37 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:21:37 INFO - 1498969297901 Marionette DEBUG Closed connection 353
21:21:37 INFO - 1498969297902 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50052
21:21:37 INFO - 1498969297903 Marionette DEBUG Closed connection 354
21:21:37 INFO - 1498969297904 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50053
21:21:37 INFO - 1498969297905 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:37 INFO - 1498969297905 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:37 INFO - 1498969297908 Marionette DEBUG Register listener.js for window 12
21:21:37 INFO - 1498969297910 Marionette TRACE 355 <- [1,1,null,{"sessionId":"791ddd36-2e9b-44e3-99d6-2c9a8e42ef92","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:37 INFO - 1498969297912 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
21:21:37 INFO - 1498969297913 Marionette TRACE 355 <- [1,2,null,{}]
21:21:37 INFO - 1498969297914 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:37 INFO - 1498969297914 Marionette TRACE 355 <- [1,3,null,{}]
21:21:37 INFO - 1498969297915 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:37 INFO - 1498969297915 Marionette TRACE 355 <- [1,4,null,{}]
21:21:37 INFO - 1498969297917 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}]
21:21:37 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498969297919 Marionette TRACE 355 <- [1,5,null,{"value":null}]
21:21:37 INFO - 1498969297920 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
21:21:37 INFO - 1498969297921 Marionette TRACE 355 <- [1,6,null,{}]
21:21:37 INFO - 1498969297923 Marionette DEBUG Closed connection 355
21:21:37 INFO - 1498969297924 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50054
21:21:37 INFO - 1498969297924 Marionette DEBUG Closed connection 356
21:21:37 INFO - 1498969297925 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50055
21:21:37 INFO - 1498969297926 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:37 INFO - 1498969297928 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:37 INFO - 1498969297930 Marionette DEBUG Register listener.js for window 12
21:21:37 INFO - 1498969297933 Marionette TRACE 357 <- [1,1,null,{"sessionId":"98bd7009-d9c3-4c1c-879d-073b3012406d","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:37 INFO - 1498969297934 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
21:21:37 INFO - 1498969297935 Marionette TRACE 357 <- [1,2,null,{}]
21:21:37 INFO - 1498969297937 Marionette DEBUG Closed connection 357
21:21:37 INFO - 1498969297938 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50056
21:21:37 INFO - 1498969297938 Marionette DEBUG Closed connection 358
21:21:37 INFO - 1498969297939 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50057
21:21:37 INFO - 1498969297940 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:37 INFO - 1498969297941 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:37 INFO - 1498969297943 Marionette DEBUG Register listener.js for window 12
21:21:37 INFO - 1498969297945 Marionette TRACE 359 <- [1,1,null,{"sessionId":"f8f8d9a3-c62e-4c23-9e17-adc3f7174f4b","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:37 INFO - 1498969297946 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49623/clicks.html"}]
21:21:37 INFO - 1498969297948 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49623/hidden.html"
21:21:37 INFO - 1498969297953 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49623/hidden.html"
21:21:37 INFO - 1498969297953 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49623/hidden.html"
21:21:37 INFO - 1498969297987 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49623/clicks.html"
21:21:37 INFO - 1498969297988 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49623/clicks.html"
21:21:37 INFO - 1498969297991 Marionette TRACE 359 <- [1,2,null,{}]
21:21:37 INFO - 1498969297992 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:21:37 INFO - 1498969297995 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dd9a164b-80e2-4351-8287-b67cc2d43f28","ELEMENT":"dd9a164b-80e2-4351-8287-b67cc2d43f28"}}]
21:21:37 INFO - 1498969297996 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"dd9a164b-80e2-4351-8287-b67cc2d43f28"}]
21:21:38 INFO - 1498969298044 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49623/clicks.html"
21:21:38 INFO - 1498969298063 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49623/clicks.html"
21:21:38 INFO - 1498969298063 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49623/clicks.html"
21:21:38 INFO - 1498969298078 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49623/test.html"
21:21:38 INFO - 1498969298083 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49623/test.html"
21:21:38 INFO - 1498969298087 Marionette TRACE 359 <- [1,4,null,{}]
21:21:38 INFO - 1498969298092 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:21:38 INFO - 1498969298095 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8014bba5-8b22-4bfd-b91f-05e4d13a69b5","ELEMENT":"8014bba5-8b22-4bfd-b91f-05e4d13a69b5"}}]
21:21:38 INFO - 1498969298103 Marionette TRACE 359 -> [0,6,"getTitle",{}]
21:21:38 INFO - 1498969298105 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
21:21:38 INFO - 1498969298112 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}]
21:21:38 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498969298116 Marionette TRACE 359 <- [1,7,null,{"value":null}]
21:21:38 INFO - 1498969298117 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
21:21:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 218ms
21:21:38 INFO - TEST-START | test_click.py TestClick.test_container_element
21:21:38 INFO - 1498969298118 Marionette TRACE 359 <- [1,8,null,{}]
21:21:38 INFO - 1498969298120 Marionette DEBUG Closed connection 359
21:21:38 INFO - 1498969298121 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50060
21:21:38 INFO - 1498969298121 Marionette DEBUG Closed connection 360
21:21:38 INFO - 1498969298122 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50061
21:21:38 INFO - 1498969298123 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:38 INFO - 1498969298124 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298126 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298129 Marionette TRACE 361 <- [1,1,null,{"sessionId":"02fe032c-9d28-4d1a-bf31-72e4153dedb5","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298130 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
21:21:38 INFO - 1498969298130 Marionette TRACE 361 <- [1,2,null,{}]
21:21:38 INFO - 1498969298131 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:38 INFO - 1498969298132 Marionette TRACE 361 <- [1,3,null,{}]
21:21:38 INFO - 1498969298132 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:38 INFO - 1498969298133 Marionette TRACE 361 <- [1,4,null,{}]
21:21:38 INFO - 1498969298135 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}]
21:21:38 INFO - TEST-START: test_click.py TestClick.test_container_element1498969298137 Marionette TRACE 361 <- [1,5,null,{"value":null}]
21:21:38 INFO - 1498969298138 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
21:21:38 INFO - 1498969298139 Marionette TRACE 361 <- [1,6,null,{}]
21:21:38 INFO - 1498969298141 Marionette DEBUG Closed connection 361
21:21:38 INFO - 1498969298141 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50062
21:21:38 INFO - 1498969298142 Marionette DEBUG Closed connection 362
21:21:38 INFO - 1498969298143 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50063
21:21:38 INFO - 1498969298144 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:38 INFO - 1498969298144 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298146 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298149 Marionette TRACE 363 <- [1,1,null,{"sessionId":"ccd67a0b-4e96-4327-b4d6-ed70525a78b8","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298150 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
21:21:38 INFO - 1498969298151 Marionette TRACE 363 <- [1,2,null,{}]
21:21:38 INFO - 1498969298153 Marionette DEBUG Closed connection 363
21:21:38 INFO - 1498969298153 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50064
21:21:38 INFO - 1498969298154 Marionette DEBUG Closed connection 364
21:21:38 INFO - 1498969298155 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50065
21:21:38 INFO - 1498969298156 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:38 INFO - 1498969298156 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298158 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298161 Marionette TRACE 365 <- [1,1,null,{"sessionId":"1a2de778-1fa6-4d16-8989-4271248f0765","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298162 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"}]
21:21:38 INFO - 1498969298163 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49623/test.html"
21:21:38 INFO - 1498969298166 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49623/test.html"
21:21:38 INFO - 1498969298167 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49623/test.html"
21:21:38 INFO - 1498969298194 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"
21:21:38 INFO - 1498969298200 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"
21:21:38 INFO - 1498969298212 Marionette TRACE 365 <- [1,2,null,{}]
21:21:38 INFO - 1498969298213 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:21:38 INFO - 1498969298217 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e5e9ee82-6a9f-4d28-995d-333ee540024e","ELEMENT":"e5e9ee82-6a9f-4d28-995d-333ee540024e"}}]
21:21:38 INFO - 1498969298219 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"e5e9ee82-6a9f-4d28-995d-333ee540024e"}]
21:21:38 INFO - 1498969298455 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:38 INFO - 1498969298456 Marionette TRACE 365 <- [1,4,null,{}]
21:21:38 INFO - 1498969298457 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"e5e9ee82-6a9f-4d28-995d-333ee540024e","name":"selected"}]
21:21:38 INFO - 1498969298461 Marionette TRACE 365 <- [1,5,null,{"value":true}]
21:21:38 INFO - 1498969298463 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}]
21:21:38 INFO - TEST-END: test_click.py TestClick.test_container_element1498969298465 Marionette TRACE 365 <- [1,6,null,{"value":null}]
21:21:38 INFO - 1498969298466 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
21:21:38 INFO - 1498969298467 Marionette TRACE 365 <- [1,7,null,{}]
21:21:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 349ms
21:21:38 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:21:38 INFO - 1498969298469 Marionette DEBUG Closed connection 365
21:21:38 INFO - 1498969298470 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50066
21:21:38 INFO - 1498969298471 Marionette DEBUG Closed connection 366
21:21:38 INFO - 1498969298471 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50067
21:21:38 INFO - 1498969298472 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:38 INFO - 1498969298473 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298475 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298478 Marionette TRACE 367 <- [1,1,null,{"sessionId":"e50c011a-61c4-409c-85a5-449f5eb5d615","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298479 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
21:21:38 INFO - 1498969298479 Marionette TRACE 367 <- [1,2,null,{}]
21:21:38 INFO - 1498969298480 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:38 INFO - 1498969298481 Marionette TRACE 367 <- [1,3,null,{}]
21:21:38 INFO - 1498969298482 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:38 INFO - 1498969298482 Marionette TRACE 367 <- [1,4,null,{}]
21:21:38 INFO - 1498969298484 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}]
21:21:38 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498969298486 Marionette TRACE 367 <- [1,5,null,{"value":null}]
21:21:38 INFO - 1498969298487 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
21:21:38 INFO - 1498969298488 Marionette TRACE 367 <- [1,6,null,{}]
21:21:38 INFO - 1498969298490 Marionette DEBUG Closed connection 367
21:21:38 INFO - 1498969298490 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50068
21:21:38 INFO - 1498969298491 Marionette DEBUG Closed connection 368
21:21:38 INFO - 1498969298492 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50069
21:21:38 INFO - 1498969298493 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:38 INFO - 1498969298493 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298495 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298499 Marionette TRACE 369 <- [1,1,null,{"sessionId":"0e2bd8a2-509d-4ca7-a359-2ab7b63f55ff","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298500 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
21:21:38 INFO - 1498969298501 Marionette TRACE 369 <- [1,2,null,{}]
21:21:38 INFO - 1498969298503 Marionette DEBUG Closed connection 369
21:21:38 INFO - 1498969298504 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50070
21:21:38 INFO - 1498969298504 Marionette DEBUG Closed connection 370
21:21:38 INFO - 1498969298505 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50071
21:21:38 INFO - 1498969298506 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:38 INFO - 1498969298507 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298508 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298511 Marionette TRACE 371 <- [1,1,null,{"sessionId":"89dc6993-a5b4-47c5-b53b-296ef417c48a","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298512 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"}]
21:21:38 INFO - 1498969298514 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"
21:21:38 INFO - 1498969298516 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"
21:21:38 INFO - 1498969298516 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"
21:21:38 INFO - 1498969298526 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"
21:21:38 INFO - 1498969298530 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"
21:21:38 INFO - 1498969298539 Marionette TRACE 371 <- [1,2,null,{}]
21:21:38 INFO - 1498969298541 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:21:38 INFO - 1498969298543 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"af9e1631-e496-4ec8-8a8d-0ca8d34bcd73","ELEMENT":"af9e1631-e496-4ec8-8a8d-0ca8d34bcd73"}}]
21:21:38 INFO - 1498969298544 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"af9e1631-e496-4ec8-8a8d-0ca8d34bcd73"}]
21:21:38 INFO - 1498969298790 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:38 INFO - 1498969298791 Marionette TRACE 371 <- [1,4,null,{}]
21:21:38 INFO - 1498969298792 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"af9e1631-e496-4ec8-8a8d-0ca8d34bcd73","name":"selected"}]
21:21:38 INFO - 1498969298796 Marionette TRACE 371 <- [1,5,null,{"value":true}]
21:21:38 INFO - 1498969298798 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}]
21:21:38 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498969298800 Marionette TRACE 371 <- [1,6,null,{"value":null}]
21:21:38 INFO - 1498969298801 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
21:21:38 INFO - 1498969298802 Marionette TRACE 371 <- [1,7,null,{}]
21:21:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 335ms
21:21:38 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:21:38 INFO - 1498969298804 Marionette DEBUG Closed connection 371
21:21:38 INFO - 1498969298805 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50072
21:21:38 INFO - 1498969298806 Marionette DEBUG Closed connection 372
21:21:38 INFO - 1498969298807 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50073
21:21:38 INFO - 1498969298808 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:38 INFO - 1498969298808 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298810 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298813 Marionette TRACE 373 <- [1,1,null,{"sessionId":"aaa29c17-1657-433a-91ab-0107de1c3074","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298814 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
21:21:38 INFO - 1498969298814 Marionette TRACE 373 <- [1,2,null,{}]
21:21:38 INFO - 1498969298815 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:38 INFO - 1498969298816 Marionette TRACE 373 <- [1,3,null,{}]
21:21:38 INFO - 1498969298817 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:38 INFO - 1498969298817 Marionette TRACE 373 <- [1,4,null,{}]
21:21:38 INFO - 1498969298819 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}]
21:21:38 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498969298821 Marionette TRACE 373 <- [1,5,null,{"value":null}]
21:21:38 INFO - 1498969298822 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
21:21:38 INFO - 1498969298823 Marionette TRACE 373 <- [1,6,null,{}]
21:21:38 INFO - 1498969298825 Marionette DEBUG Closed connection 373
21:21:38 INFO - 1498969298826 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50074
21:21:38 INFO - 1498969298826 Marionette DEBUG Closed connection 374
21:21:38 INFO - 1498969298827 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50075
21:21:38 INFO - 1498969298828 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:38 INFO - 1498969298829 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298831 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298833 Marionette TRACE 375 <- [1,1,null,{"sessionId":"5c2287ac-2160-4ae2-91d7-164051654c63","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298834 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
21:21:38 INFO - 1498969298835 Marionette TRACE 375 <- [1,2,null,{}]
21:21:38 INFO - 1498969298837 Marionette DEBUG Closed connection 375
21:21:38 INFO - 1498969298838 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50076
21:21:38 INFO - 1498969298838 Marionette DEBUG Closed connection 376
21:21:38 INFO - 1498969298839 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50077
21:21:38 INFO - 1498969298840 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:38 INFO - 1498969298841 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:38 INFO - 1498969298842 Marionette DEBUG Register listener.js for window 12
21:21:38 INFO - 1498969298845 Marionette TRACE 377 <- [1,1,null,{"sessionId":"3418e9a7-41ab-42d4-918d-c5c275df7520","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:38 INFO - 1498969298846 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"}]
21:21:38 INFO - 1498969298848 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"
21:21:38 INFO - 1498969298850 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"
21:21:38 INFO - 1498969298851 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"
21:21:38 INFO - 1498969298862 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"
21:21:38 INFO - 1498969298865 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"
21:21:38 INFO - 1498969298876 Marionette TRACE 377 <- [1,2,null,{}]
21:21:38 INFO - 1498969298878 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:21:38 INFO - 1498969298881 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8655da8-63d0-4725-b250-0fc8e177e29c","ELEMENT":"e8655da8-63d0-4725-b250-0fc8e177e29c"}}]
21:21:38 INFO - 1498969298882 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"e8655da8-63d0-4725-b250-0fc8e177e29c"}]
21:21:39 INFO - 1498969299117 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:39 INFO - 1498969299118 Marionette TRACE 377 <- [1,4,null,{}]
21:21:39 INFO - 1498969299120 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}]
21:21:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498969299122 Marionette TRACE 377 <- [1,5,null,{"value":null}]
21:21:39 INFO - 1498969299123 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
21:21:39 INFO - 1498969299124 Marionette TRACE 377 <- [1,6,null,{}]
21:21:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
21:21:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:21:39 INFO - 1498969299126 Marionette DEBUG Closed connection 377
21:21:39 INFO - 1498969299127 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50078
21:21:39 INFO - 1498969299128 Marionette DEBUG Closed connection 378
21:21:39 INFO - 1498969299128 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50079
21:21:39 INFO - 1498969299129 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:39 INFO - 1498969299130 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299132 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299135 Marionette TRACE 379 <- [1,1,null,{"sessionId":"28a2b4d5-0cf9-475d-869c-3beb8c993d7c","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299136 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
21:21:39 INFO - 1498969299136 Marionette TRACE 379 <- [1,2,null,{}]
21:21:39 INFO - 1498969299137 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:39 INFO - 1498969299137 Marionette TRACE 379 <- [1,3,null,{}]
21:21:39 INFO - 1498969299138 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:39 INFO - 1498969299139 Marionette TRACE 379 <- [1,4,null,{}]
21:21:39 INFO - 1498969299141 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}]
21:21:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498969299143 Marionette TRACE 379 <- [1,5,null,{"value":null}]
21:21:39 INFO - 1498969299144 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
21:21:39 INFO - 1498969299145 Marionette TRACE 379 <- [1,6,null,{}]
21:21:39 INFO - 1498969299147 Marionette DEBUG Closed connection 379
21:21:39 INFO - 1498969299147 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50080
21:21:39 INFO - 1498969299148 Marionette DEBUG Closed connection 380
21:21:39 INFO - 1498969299149 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50081
21:21:39 INFO - 1498969299150 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:39 INFO - 1498969299150 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299152 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299155 Marionette TRACE 381 <- [1,1,null,{"sessionId":"a42c2e7c-e13b-4643-982b-57831346fe91","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299156 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
21:21:39 INFO - 1498969299157 Marionette TRACE 381 <- [1,2,null,{}]
21:21:39 INFO - 1498969299159 Marionette DEBUG Closed connection 381
21:21:39 INFO - 1498969299159 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50082
21:21:39 INFO - 1498969299160 Marionette DEBUG Closed connection 382
21:21:39 INFO - 1498969299161 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50083
21:21:39 INFO - 1498969299162 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:39 INFO - 1498969299162 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299164 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299167 Marionette TRACE 383 <- [1,1,null,{"sessionId":"1eef6396-940a-43db-bc7b-07b5685d0325","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299168 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"}]
21:21:39 INFO - 1498969299169 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"
21:21:39 INFO - 1498969299171 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"
21:21:39 INFO - 1498969299172 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"
21:21:39 INFO - 1498969299188 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"
21:21:39 INFO - 1498969299189 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"
21:21:39 INFO - 1498969299192 Marionette TRACE 383 <- [1,2,null,{}]
21:21:39 INFO - 1498969299194 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:21:39 INFO - 1498969299196 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"852566a8-075e-4ff5-b34f-08622e65ba1c","ELEMENT":"852566a8-075e-4ff5-b34f-08622e65ba1c"}}]
21:21:39 INFO - 1498969299204 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"852566a8-075e-4ff5-b34f-08622e65ba1c"}]
21:21:39 INFO - 1498969299450 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:39 INFO - 1498969299451 Marionette TRACE 383 <- [1,4,null,{}]
21:21:39 INFO - 1498969299453 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}]
21:21:39 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498969299455 Marionette TRACE 383 <- [1,5,null,{"value":null}]
21:21:39 INFO - 1498969299456 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
21:21:39 INFO - 1498969299457 Marionette TRACE 383 <- [1,6,null,{}]
21:21:39 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 333ms
21:21:39 INFO - TEST-START | test_click.py TestClick.test_input_file
21:21:39 INFO - 1498969299459 Marionette DEBUG Closed connection 383
21:21:39 INFO - 1498969299460 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50084
21:21:39 INFO - 1498969299461 Marionette DEBUG Closed connection 384
21:21:39 INFO - 1498969299462 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50085
21:21:39 INFO - 1498969299463 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:39 INFO - 1498969299463 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299465 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299468 Marionette TRACE 385 <- [1,1,null,{"sessionId":"ef18ce05-a496-4705-b11b-8226f082207d","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299469 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
21:21:39 INFO - 1498969299470 Marionette TRACE 385 <- [1,2,null,{}]
21:21:39 INFO - 1498969299470 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:39 INFO - 1498969299471 Marionette TRACE 385 <- [1,3,null,{}]
21:21:39 INFO - 1498969299472 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:39 INFO - 1498969299472 Marionette TRACE 385 <- [1,4,null,{}]
21:21:39 INFO - 1498969299474 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}]
21:21:39 INFO - TEST-START: test_click.py TestClick.test_input_file1498969299476 Marionette TRACE 385 <- [1,5,null,{"value":null}]
21:21:39 INFO - 1498969299477 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
21:21:39 INFO - 1498969299478 Marionette TRACE 385 <- [1,6,null,{}]
21:21:39 INFO - 1498969299480 Marionette DEBUG Closed connection 385
21:21:39 INFO - 1498969299480 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50086
21:21:39 INFO - 1498969299481 Marionette DEBUG Closed connection 386
21:21:39 INFO - 1498969299482 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50087
21:21:39 INFO - 1498969299483 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:39 INFO - 1498969299483 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299485 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299488 Marionette TRACE 387 <- [1,1,null,{"sessionId":"33467dd5-2461-42ab-b82f-877c12667a95","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299489 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
21:21:39 INFO - 1498969299490 Marionette TRACE 387 <- [1,2,null,{}]
21:21:39 INFO - 1498969299491 Marionette DEBUG Closed connection 387
21:21:39 INFO - 1498969299492 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50088
21:21:39 INFO - 1498969299493 Marionette DEBUG Closed connection 388
21:21:39 INFO - 1498969299494 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50089
21:21:39 INFO - 1498969299495 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:39 INFO - 1498969299495 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299497 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299500 Marionette TRACE 389 <- [1,1,null,{"sessionId":"383b23e5-01af-493b-9ac6-19f3458090df","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299501 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:21:39 INFO - 1498969299502 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"
21:21:39 INFO - 1498969299504 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"
21:21:39 INFO - 1498969299505 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"
21:21:39 INFO - 1498969299521 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:39 INFO - 1498969299523 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:39 INFO - 1498969299527 Marionette TRACE 389 <- [1,2,null,{}]
21:21:39 INFO - 1498969299528 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:21:39 INFO - 1498969299537 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d706012d-8353-497c-9197-0e0d4652326e","ELEMENT":"d706012d-8353-497c-9197-0e0d4652326e"}}]
21:21:39 INFO - 1498969299538 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"d706012d-8353-497c-9197-0e0d4652326e"}]
21:21:39 INFO - 1498969299563 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]
21:21:39 INFO - 1498969299565 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}]
21:21:39 INFO - TEST-END: test_click.py TestClick.test_input_file1498969299567 Marionette TRACE 389 <- [1,5,null,{"value":null}]
21:21:39 INFO - 1498969299568 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
21:21:39 INFO - 1498969299570 Marionette TRACE 389 <- [1,6,null,{}]
21:21:39 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 111ms
21:21:39 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:21:39 INFO - 1498969299571 Marionette DEBUG Closed connection 389
21:21:39 INFO - 1498969299572 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50090
21:21:39 INFO - 1498969299573 Marionette DEBUG Closed connection 390
21:21:39 INFO - 1498969299574 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50091
21:21:39 INFO - 1498969299575 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:39 INFO - 1498969299575 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299578 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299580 Marionette TRACE 391 <- [1,1,null,{"sessionId":"2951897f-c8ef-4bac-82cd-6edabf5b5140","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299581 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
21:21:39 INFO - 1498969299582 Marionette TRACE 391 <- [1,2,null,{}]
21:21:39 INFO - 1498969299583 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:39 INFO - 1498969299583 Marionette TRACE 391 <- [1,3,null,{}]
21:21:39 INFO - 1498969299584 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:39 INFO - 1498969299585 Marionette TRACE 391 <- [1,4,null,{}]
21:21:39 INFO - 1498969299586 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}]
21:21:39 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498969299589 Marionette TRACE 391 <- [1,5,null,{"value":null}]
21:21:39 INFO - 1498969299589 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
21:21:39 INFO - 1498969299590 Marionette TRACE 391 <- [1,6,null,{}]
21:21:39 INFO - 1498969299592 Marionette DEBUG Closed connection 391
21:21:39 INFO - 1498969299593 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50092
21:21:39 INFO - 1498969299593 Marionette DEBUG Closed connection 392
21:21:39 INFO - 1498969299594 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50093
21:21:39 INFO - 1498969299595 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:39 INFO - 1498969299596 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299598 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299601 Marionette TRACE 393 <- [1,1,null,{"sessionId":"8cf41654-6578-4aa3-ae7e-cb19595503c3","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299601 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
21:21:39 INFO - 1498969299602 Marionette TRACE 393 <- [1,2,null,{}]
21:21:39 INFO - 1498969299604 Marionette DEBUG Closed connection 393
21:21:39 INFO - 1498969299605 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50094
21:21:39 INFO - 1498969299605 Marionette DEBUG Closed connection 394
21:21:39 INFO - 1498969299606 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50095
21:21:39 INFO - 1498969299607 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:39 INFO - 1498969299608 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299609 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299612 Marionette TRACE 395 <- [1,1,null,{"sessionId":"3a69d6ae-5d58-4223-88c1-5ff8ce3c49d3","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299613 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"}]
21:21:39 INFO - 1498969299614 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:39 INFO - 1498969299616 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:39 INFO - 1498969299617 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:21:39 INFO - 1498969299627 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"
21:21:39 INFO - 1498969299631 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"
21:21:39 INFO - 1498969299640 Marionette TRACE 395 <- [1,2,null,{}]
21:21:39 INFO - 1498969299641 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:21:39 INFO - 1498969299643 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1b9f10c5-4503-4216-a75f-de341e5a3c27","ELEMENT":"1b9f10c5-4503-4216-a75f-de341e5a3c27"}}]
21:21:39 INFO - 1498969299644 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:21:39 INFO - 1498969299646 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f523b7f-cea9-49a7-96cb-6c7f54757d9a","ELEMENT":"2f523b7f-cea9-49a7-96cb-6c7f54757d9a"}}]
21:21:39 INFO - 1498969299647 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"1b9f10c5-4503-4216-a75f-de341e5a3c27"}]
21:21:39 INFO - 1498969299889 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:21:39 INFO - 1498969299890 Marionette TRACE 395 <- [1,5,null,{}]
21:21:39 INFO - 1498969299891 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"2f523b7f-cea9-49a7-96cb-6c7f54757d9a"}]
21:21:39 INFO - 1498969299905 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]
21:21:39 INFO - 1498969299907 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:21:39 INFO - 1498969299909 Marionette TRACE 395 <- [1,7,null,{"value":false}]
21:21:39 INFO - 1498969299911 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}]
21:21:39 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498969299913 Marionette TRACE 395 <- [1,8,null,{"value":null}]
21:21:39 INFO - 1498969299914 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
21:21:39 INFO - 1498969299916 Marionette TRACE 395 <- [1,9,null,{}]
21:21:39 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 345ms
21:21:39 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:21:39 INFO - 1498969299917 Marionette DEBUG Closed connection 395
21:21:39 INFO - 1498969299918 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50096
21:21:39 INFO - 1498969299919 Marionette DEBUG Closed connection 396
21:21:39 INFO - 1498969299920 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50097
21:21:39 INFO - 1498969299921 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:39 INFO - 1498969299921 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299923 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299926 Marionette TRACE 397 <- [1,1,null,{"sessionId":"31adc5a5-8914-44b0-8f2b-8e93f69594f3","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299927 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
21:21:39 INFO - 1498969299928 Marionette TRACE 397 <- [1,2,null,{}]
21:21:39 INFO - 1498969299929 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:21:39 INFO - 1498969299929 Marionette TRACE 397 <- [1,3,null,{}]
21:21:39 INFO - 1498969299930 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
21:21:39 INFO - 1498969299931 Marionette TRACE 397 <- [1,4,null,{}]
21:21:39 INFO - 1498969299932 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}]
21:21:39 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498969299935 Marionette TRACE 397 <- [1,5,null,{"value":null}]
21:21:39 INFO - 1498969299935 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
21:21:39 INFO - 1498969299936 Marionette TRACE 397 <- [1,6,null,{}]
21:21:39 INFO - 1498969299938 Marionette DEBUG Closed connection 397
21:21:39 INFO - 1498969299939 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50098
21:21:39 INFO - 1498969299939 Marionette DEBUG Closed connection 398
21:21:39 INFO - 1498969299940 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50099
21:21:39 INFO - 1498969299941 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:21:39 INFO - 1498969299942 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299944 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299947 Marionette TRACE 399 <- [1,1,null,{"sessionId":"faf06bdd-e392-4686-8eb0-3e4467497e5e","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299948 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
21:21:39 INFO - 1498969299949 Marionette TRACE 399 <- [1,2,null,{}]
21:21:39 INFO - 1498969299950 Marionette DEBUG Closed connection 399
21:21:39 INFO - 1498969299951 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50100
21:21:39 INFO - 1498969299952 Marionette DEBUG Closed connection 400
21:21:39 INFO - 1498969299952 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50101
21:21:39 INFO - 1498969299953 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:21:39 INFO - 1498969299954 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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}
21:21:39 INFO - 1498969299956 Marionette DEBUG Register listener.js for window 12
21:21:39 INFO - 1498969299958 Marionette TRACE 401 <- [1,1,null,{"sessionId":"81ad9a29-ff59-4a1b-bcf6-6017875d5909","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":3712,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp8gdepn.mozrunner","moz:accessibilityChecks":false}}]
21:21:39 INFO - 1498969299960 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"}]
21:21:39 INFO - 1498969299961 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"
21:21:39 INFO - 1498969299963 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"
21:21:39 INFO - 1498969299963 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"
21:21:39 INFO - 1498969299979 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"
21:21:39 INFO - 1498969299982 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"
21:21:39 INFO - 1498969299990 Marionette TRACE 401 <- [1,2,null,{}]
21:21:39 INFO - 1498969299992 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:21:39 INFO - 1498969299995 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a5be716a-0794-4caf-a4c6-2e73cc26e641","ELEMENT":"a5be716a-0794-4caf-a4c6-2e73cc26e641"}}]
21:21:39 INFO - 1498969299995 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a5be716a-0794-4caf-a4c6-2e73cc26e641"}]
21:21:40 INFO - 1498969300016 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
21:21:40 INFO - 1498969300017 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"a5be716a-0794-4caf-a4c6-2e73cc26e641"}]
21:21:40 INFO - 1498969300035 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element