could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:57:47 INFO - 1500818267953 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
06:57:47 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500818267955 Marionette TRACE 352 <- [1,5,null,{"value":null}]
06:57:47 INFO - 1500818267956 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
06:57:47 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 113ms
06:57:47 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
06:57:47 INFO - 1500818267958 Marionette TRACE 352 <- [1,6,null,{}]
06:57:47 INFO - 1500818267960 Marionette DEBUG Closed connection 352
06:57:47 INFO - 1500818267960 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50569
06:57:47 INFO - 1500818267962 Marionette DEBUG Closed connection 353
06:57:47 INFO - 1500818267962 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50570
06:57:47 INFO - 1500818267963 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:47 INFO - 1500818267964 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:47 INFO - 1500818267966 Marionette DEBUG Register listener.js for window 4294967297
06:57:47 INFO - 1500818267969 Marionette TRACE 354 <- [1,1,null,{"sessionId":"f597bd40-6f27-4d53-9755-3a7ebe410270","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:47 INFO - 1500818267970 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
06:57:47 INFO - 1500818267970 Marionette TRACE 354 <- [1,2,null,{}]
06:57:47 INFO - 1500818267971 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:57:47 INFO - 1500818267972 Marionette TRACE 354 <- [1,3,null,{}]
06:57:47 INFO - 1500818267973 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
06:57:47 INFO - 1500818267973 Marionette TRACE 354 <- [1,4,null,{}]
06:57:47 INFO - 1500818267975 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
06:57:47 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500818267977 Marionette TRACE 354 <- [1,5,null,{"value":null}]
06:57:47 INFO - 1500818267978 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
06:57:47 INFO - 1500818267979 Marionette TRACE 354 <- [1,6,null,{}]
06:57:47 INFO - 1500818267980 Marionette DEBUG Closed connection 354
06:57:47 INFO - 1500818267981 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50571
06:57:47 INFO - 1500818267981 Marionette DEBUG Closed connection 355
06:57:47 INFO - 1500818267982 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50572
06:57:47 INFO - 1500818267983 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:47 INFO - 1500818267984 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:47 INFO - 1500818267986 Marionette DEBUG Register listener.js for window 4294967297
06:57:47 INFO - 1500818267989 Marionette TRACE 356 <- [1,1,null,{"sessionId":"83e7b060-07a1-4352-8e9a-640c18dd6385","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:47 INFO - 1500818267990 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
06:57:47 INFO - 1500818267991 Marionette TRACE 356 <- [1,2,null,{}]
06:57:47 INFO - 1500818267992 Marionette DEBUG Closed connection 356
06:57:47 INFO - 1500818267993 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50573
06:57:47 INFO - 1500818267993 Marionette DEBUG Closed connection 357
06:57:47 INFO - 1500818267994 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50574
06:57:47 INFO - 1500818267995 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:57:47 INFO - 1500818267995 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818267997 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268000 Marionette TRACE 358 <- [1,1,null,{"sessionId":"d5007bbd-2431-45fd-93f3-2a6c3a0af5bc","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268001 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:50130/clicks.html"}]
06:57:48 INFO - 1500818268003 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50130/hidden.html"
06:57:48 INFO - 1500818268011 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50130/hidden.html"
06:57:48 INFO - 1500818268011 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50130/hidden.html"
06:57:48 INFO - 1500818268024 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50130/clicks.html"
06:57:48 INFO - 1500818268028 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50130/clicks.html"
06:57:48 INFO - 1500818268032 Marionette TRACE 358 <- [1,2,null,{}]
06:57:48 INFO - 1500818268036 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
06:57:48 INFO - 1500818268038 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e80b9db8-b0ea-49c6-9179-2613f0666454","ELEMENT":"e80b9db8-b0ea-49c6-9179-2613f0666454"}}]
06:57:48 INFO - 1500818268039 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"e80b9db8-b0ea-49c6-9179-2613f0666454"}]
06:57:48 INFO - 1500818268076 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50130/clicks.html"
06:57:48 INFO - 1500818268086 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50130/clicks.html"
06:57:48 INFO - 1500818268086 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50130/clicks.html"
06:57:48 INFO - 1500818268093 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:50130/test.html"
06:57:48 INFO - 1500818268096 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:50130/test.html"
06:57:48 INFO - 1500818268114 Marionette TRACE 358 <- [1,4,null,{}]
06:57:48 INFO - 1500818268125 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
06:57:48 INFO - 1500818268134 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ceb50f3-6a24-4e01-b3fa-ff68297333ef","ELEMENT":"2ceb50f3-6a24-4e01-b3fa-ff68297333ef"}}]
06:57:48 INFO - 1500818268135 Marionette TRACE 358 -> [0,6,"getTitle",{}]
06:57:48 INFO - 1500818268135 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
06:57:48 INFO - 1500818268137 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
06:57:48 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500818268140 Marionette TRACE 358 <- [1,7,null,{"value":null}]
06:57:48 INFO - 1500818268141 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
06:57:48 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 184ms
06:57:48 INFO - TEST-START | test_click.py TestClick.test_container_element
06:57:48 INFO - 1500818268142 Marionette TRACE 358 <- [1,8,null,{}]
06:57:48 INFO - 1500818268143 Marionette DEBUG Closed connection 358
06:57:48 INFO - 1500818268144 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50577
06:57:48 INFO - 1500818268145 Marionette DEBUG Closed connection 359
06:57:48 INFO - 1500818268145 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50578
06:57:48 INFO - 1500818268146 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:48 INFO - 1500818268147 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268149 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268152 Marionette TRACE 360 <- [1,1,null,{"sessionId":"2a53d166-8685-41db-bda1-a6da598b6536","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268153 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
06:57:48 INFO - 1500818268153 Marionette TRACE 360 <- [1,2,null,{}]
06:57:48 INFO - 1500818268155 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:57:48 INFO - 1500818268155 Marionette TRACE 360 <- [1,3,null,{}]
06:57:48 INFO - 1500818268156 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
06:57:48 INFO - 1500818268156 Marionette TRACE 360 <- [1,4,null,{}]
06:57:48 INFO - 1500818268158 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
06:57:48 INFO - TEST-START: test_click.py TestClick.test_container_element1500818268161 Marionette TRACE 360 <- [1,5,null,{"value":null}]
06:57:48 INFO - 1500818268162 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
06:57:48 INFO - 1500818268163 Marionette TRACE 360 <- [1,6,null,{}]
06:57:48 INFO - 1500818268165 Marionette DEBUG Closed connection 360
06:57:48 INFO - 1500818268165 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50579
06:57:48 INFO - 1500818268166 Marionette DEBUG Closed connection 361
06:57:48 INFO - 1500818268167 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50580
06:57:48 INFO - 1500818268168 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:48 INFO - 1500818268168 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268171 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268173 Marionette TRACE 362 <- [1,1,null,{"sessionId":"723ebbb7-f786-48fa-a80e-8ca3d0e3bcba","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268174 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
06:57:48 INFO - 1500818268175 Marionette TRACE 362 <- [1,2,null,{}]
06:57:48 INFO - 1500818268177 Marionette DEBUG Closed connection 362
06:57:48 INFO - 1500818268177 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50581
06:57:48 INFO - 1500818268178 Marionette DEBUG Closed connection 363
06:57:48 INFO - 1500818268179 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50582
06:57:48 INFO - 1500818268180 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:57:48 INFO - 1500818268180 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268182 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268185 Marionette TRACE 364 <- [1,1,null,{"sessionId":"64798a40-c822-4721-bdd4-4ddbfd434525","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268186 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:57:48 INFO - 1500818268188 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:50130/test.html"
06:57:48 INFO - 1500818268190 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:50130/test.html"
06:57:48 INFO - 1500818268190 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:50130/test.html"
06:57:48 INFO - 1500818268196 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"
06:57:48 INFO - 1500818268198 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"
06:57:48 INFO - 1500818268205 Marionette TRACE 364 <- [1,2,null,{}]
06:57:48 INFO - 1500818268210 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:57:48 INFO - 1500818268216 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0c17db16-f194-44d3-a10a-4653414c9663","ELEMENT":"0c17db16-f194-44d3-a10a-4653414c9663"}}]
06:57:48 INFO - 1500818268218 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"0c17db16-f194-44d3-a10a-4653414c9663"}]
06:57:48 INFO - 1500818268457 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:57:48 INFO - 1500818268458 Marionette TRACE 364 <- [1,4,null,{}]
06:57:48 INFO - 1500818268459 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"0c17db16-f194-44d3-a10a-4653414c9663","name":"selected"}]
06:57:48 INFO - 1500818268463 Marionette TRACE 364 <- [1,5,null,{"value":true}]
06:57:48 INFO - 1500818268465 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
06:57:48 INFO - TEST-END: test_click.py TestClick.test_container_element1500818268467 Marionette TRACE 364 <- [1,6,null,{"value":null}]
06:57:48 INFO - 1500818268468 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
06:57:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 327ms
06:57:48 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
06:57:48 INFO - 1500818268470 Marionette TRACE 364 <- [1,7,null,{}]
06:57:48 INFO - 1500818268471 Marionette DEBUG Closed connection 364
06:57:48 INFO - 1500818268472 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50583
06:57:48 INFO - 1500818268473 Marionette DEBUG Closed connection 365
06:57:48 INFO - 1500818268473 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50584
06:57:48 INFO - 1500818268474 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:48 INFO - 1500818268475 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268477 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268480 Marionette TRACE 366 <- [1,1,null,{"sessionId":"1925ea2a-d821-4ff2-ae9a-2e8d14c3401c","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268481 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
06:57:48 INFO - 1500818268482 Marionette TRACE 366 <- [1,2,null,{}]
06:57:48 INFO - 1500818268483 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:57:48 INFO - 1500818268483 Marionette TRACE 366 <- [1,3,null,{}]
06:57:48 INFO - 1500818268484 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
06:57:48 INFO - 1500818268484 Marionette TRACE 366 <- [1,4,null,{}]
06:57:48 INFO - 1500818268486 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
06:57:48 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500818268489 Marionette TRACE 366 <- [1,5,null,{"value":null}]
06:57:48 INFO - 1500818268490 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
06:57:48 INFO - 1500818268491 Marionette TRACE 366 <- [1,6,null,{}]
06:57:48 INFO - 1500818268492 Marionette DEBUG Closed connection 366
06:57:48 INFO - 1500818268492 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50585
06:57:48 INFO - 1500818268493 Marionette DEBUG Closed connection 367
06:57:48 INFO - 1500818268494 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50586
06:57:48 INFO - 1500818268495 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:48 INFO - 1500818268495 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268497 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268500 Marionette TRACE 368 <- [1,1,null,{"sessionId":"0eba42eb-793f-4727-b560-d2f4ab298246","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268501 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
06:57:48 INFO - 1500818268502 Marionette TRACE 368 <- [1,2,null,{}]
06:57:48 INFO - 1500818268503 Marionette DEBUG Closed connection 368
06:57:48 INFO - 1500818268504 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50587
06:57:48 INFO - 1500818268504 Marionette DEBUG Closed connection 369
06:57:48 INFO - 1500818268505 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50588
06:57:48 INFO - 1500818268506 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:57:48 INFO - 1500818268507 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268509 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268511 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3b2393f1-4b53-4cf6-b43d-bfb3b0bb25c5","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268512 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:57:48 INFO - 1500818268514 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"
06:57:48 INFO - 1500818268516 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"
06:57:48 INFO - 1500818268517 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"
06:57:48 INFO - 1500818268522 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"
06:57:48 INFO - 1500818268524 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"
06:57:48 INFO - 1500818268527 Marionette TRACE 370 <- [1,2,null,{}]
06:57:48 INFO - 1500818268535 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
06:57:48 INFO - 1500818268538 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9859d63a-8753-466c-b5c9-bcf52a7bcd5d","ELEMENT":"9859d63a-8753-466c-b5c9-bcf52a7bcd5d"}}]
06:57:48 INFO - 1500818268539 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9859d63a-8753-466c-b5c9-bcf52a7bcd5d"}]
06:57:48 INFO - 1500818268777 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:57:48 INFO - 1500818268778 Marionette TRACE 370 <- [1,4,null,{}]
06:57:48 INFO - 1500818268779 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9859d63a-8753-466c-b5c9-bcf52a7bcd5d","name":"selected"}]
06:57:48 INFO - 1500818268783 Marionette TRACE 370 <- [1,5,null,{"value":true}]
06:57:48 INFO - 1500818268785 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
06:57:48 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500818268787 Marionette TRACE 370 <- [1,6,null,{"value":null}]
06:57:48 INFO - 1500818268788 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
06:57:48 INFO - 1500818268789 Marionette TRACE 370 <- [1,7,null,{}]
06:57:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 320ms
06:57:48 INFO - TEST-START | test_click.py TestClick.test_css_transforms
06:57:48 INFO - 1500818268790 Marionette DEBUG Closed connection 370
06:57:48 INFO - 1500818268791 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50589
06:57:48 INFO - 1500818268792 Marionette DEBUG Closed connection 371
06:57:48 INFO - 1500818268793 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50590
06:57:48 INFO - 1500818268794 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:48 INFO - 1500818268794 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268796 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268799 Marionette TRACE 372 <- [1,1,null,{"sessionId":"aa2d7124-95f4-42ec-8816-1dd6b24c9b80","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268800 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
06:57:48 INFO - 1500818268801 Marionette TRACE 372 <- [1,2,null,{}]
06:57:48 INFO - 1500818268801 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:57:48 INFO - 1500818268802 Marionette TRACE 372 <- [1,3,null,{}]
06:57:48 INFO - 1500818268803 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
06:57:48 INFO - 1500818268803 Marionette TRACE 372 <- [1,4,null,{}]
06:57:48 INFO - 1500818268805 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
06:57:48 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500818268807 Marionette TRACE 372 <- [1,5,null,{"value":null}]
06:57:48 INFO - 1500818268808 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
06:57:48 INFO - 1500818268809 Marionette TRACE 372 <- [1,6,null,{}]
06:57:48 INFO - 1500818268811 Marionette DEBUG Closed connection 372
06:57:48 INFO - 1500818268811 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50591
06:57:48 INFO - 1500818268812 Marionette DEBUG Closed connection 373
06:57:48 INFO - 1500818268813 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50592
06:57:48 INFO - 1500818268813 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:48 INFO - 1500818268814 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268816 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268819 Marionette TRACE 374 <- [1,1,null,{"sessionId":"9ba8ccb9-3a50-43ae-960e-7f36ec3a8a58","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268820 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
06:57:48 INFO - 1500818268821 Marionette TRACE 374 <- [1,2,null,{}]
06:57:48 INFO - 1500818268823 Marionette DEBUG Closed connection 374
06:57:48 INFO - 1500818268823 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50593
06:57:48 INFO - 1500818268824 Marionette DEBUG Closed connection 375
06:57:48 INFO - 1500818268825 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50594
06:57:48 INFO - 1500818268826 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:57:48 INFO - 1500818268826 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:48 INFO - 1500818268828 Marionette DEBUG Register listener.js for window 4294967297
06:57:48 INFO - 1500818268839 Marionette TRACE 376 <- [1,1,null,{"sessionId":"09e4dd9a-08cd-40d2-8f1d-0a2866f439f5","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:48 INFO - 1500818268840 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
06:57:48 INFO - 1500818268842 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"
06:57:48 INFO - 1500818268844 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"
06:57:48 INFO - 1500818268844 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"
06:57:48 INFO - 1500818268851 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"
06:57:48 INFO - 1500818268853 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"
06:57:48 INFO - 1500818268857 Marionette TRACE 376 <- [1,2,null,{}]
06:57:48 INFO - 1500818268866 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
06:57:48 INFO - 1500818268869 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a81d00d1-f95f-4859-9676-4a93d39f6694","ELEMENT":"a81d00d1-f95f-4859-9676-4a93d39f6694"}}]
06:57:48 INFO - 1500818268870 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"a81d00d1-f95f-4859-9676-4a93d39f6694"}]
06:57:49 INFO - 1500818269113 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:57:49 INFO - 1500818269113 Marionette TRACE 376 <- [1,4,null,{}]
06:57:49 INFO - 1500818269115 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
06:57:49 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500818269118 Marionette TRACE 376 <- [1,5,null,{"value":null}]
06:57:49 INFO - 1500818269119 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
06:57:49 INFO - 1500818269120 Marionette TRACE 376 <- [1,6,null,{}]
06:57:49 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 331ms
06:57:49 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
06:57:49 INFO - 1500818269121 Marionette DEBUG Closed connection 376
06:57:49 INFO - 1500818269123 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50595
06:57:49 INFO - 1500818269124 Marionette DEBUG Closed connection 377
06:57:49 INFO - 1500818269124 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50596
06:57:49 INFO - 1500818269125 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:49 INFO - 1500818269126 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269128 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269131 Marionette TRACE 378 <- [1,1,null,{"sessionId":"571d3ef5-52bf-480f-802b-045cf9040e86","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269132 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
06:57:49 INFO - 1500818269132 Marionette TRACE 378 <- [1,2,null,{}]
06:57:49 INFO - 1500818269133 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:57:49 INFO - 1500818269133 Marionette TRACE 378 <- [1,3,null,{}]
06:57:49 INFO - 1500818269134 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
06:57:49 INFO - 1500818269135 Marionette TRACE 378 <- [1,4,null,{}]
06:57:49 INFO - 1500818269136 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
06:57:49 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500818269139 Marionette TRACE 378 <- [1,5,null,{"value":null}]
06:57:49 INFO - 1500818269139 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
06:57:49 INFO - 1500818269140 Marionette TRACE 378 <- [1,6,null,{}]
06:57:49 INFO - 1500818269142 Marionette DEBUG Closed connection 378
06:57:49 INFO - 1500818269142 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50597
06:57:49 INFO - 1500818269143 Marionette DEBUG Closed connection 379
06:57:49 INFO - 1500818269144 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50598
06:57:49 INFO - 1500818269145 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:49 INFO - 1500818269145 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269147 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269150 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8713c9c6-0254-4ce6-8d32-29f8715d6349","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269151 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
06:57:49 INFO - 1500818269152 Marionette TRACE 380 <- [1,2,null,{}]
06:57:49 INFO - 1500818269153 Marionette DEBUG Closed connection 380
06:57:49 INFO - 1500818269154 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50599
06:57:49 INFO - 1500818269154 Marionette DEBUG Closed connection 381
06:57:49 INFO - 1500818269155 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50600
06:57:49 INFO - 1500818269156 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:57:49 INFO - 1500818269157 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269159 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269162 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2a6996f0-0850-4e01-8941-f094ed17588b","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269163 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
06:57:49 INFO - 1500818269165 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"
06:57:49 INFO - 1500818269166 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"
06:57:49 INFO - 1500818269167 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"
06:57:49 INFO - 1500818269172 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"
06:57:49 INFO - 1500818269175 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"
06:57:49 INFO - 1500818269177 Marionette TRACE 382 <- [1,2,null,{}]
06:57:49 INFO - 1500818269185 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
06:57:49 INFO - 1500818269189 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5bdd1168-6848-45a7-b027-034340e8f697","ELEMENT":"5bdd1168-6848-45a7-b027-034340e8f697"}}]
06:57:49 INFO - 1500818269190 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"5bdd1168-6848-45a7-b027-034340e8f697"}]
06:57:49 INFO - 1500818269428 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:57:49 INFO - 1500818269428 Marionette TRACE 382 <- [1,4,null,{}]
06:57:49 INFO - 1500818269430 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
06:57:49 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500818269433 Marionette TRACE 382 <- [1,5,null,{"value":null}]
06:57:49 INFO - 1500818269434 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
06:57:49 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 313ms
06:57:49 INFO - TEST-START | test_click.py TestClick.test_input_file
06:57:49 INFO - 1500818269435 Marionette TRACE 382 <- [1,6,null,{}]
06:57:49 INFO - 1500818269436 Marionette DEBUG Closed connection 382
06:57:49 INFO - 1500818269437 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50601
06:57:49 INFO - 1500818269438 Marionette DEBUG Closed connection 383
06:57:49 INFO - 1500818269439 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50602
06:57:49 INFO - 1500818269440 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:49 INFO - 1500818269440 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269442 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269445 Marionette TRACE 384 <- [1,1,null,{"sessionId":"3471ccdb-bff4-4936-8e7d-a06e7bed24ea","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269446 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
06:57:49 INFO - 1500818269447 Marionette TRACE 384 <- [1,2,null,{}]
06:57:49 INFO - 1500818269447 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:57:49 INFO - 1500818269448 Marionette TRACE 384 <- [1,3,null,{}]
06:57:49 INFO - 1500818269448 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
06:57:49 INFO - 1500818269449 Marionette TRACE 384 <- [1,4,null,{}]
06:57:49 INFO - 1500818269450 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
06:57:49 INFO - TEST-START: test_click.py TestClick.test_input_file1500818269453 Marionette TRACE 384 <- [1,5,null,{"value":null}]
06:57:49 INFO - 1500818269454 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
06:57:49 INFO - 1500818269455 Marionette TRACE 384 <- [1,6,null,{}]
06:57:49 INFO - 1500818269456 Marionette DEBUG Closed connection 384
06:57:49 INFO - 1500818269456 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50603
06:57:49 INFO - 1500818269457 Marionette DEBUG Closed connection 385
06:57:49 INFO - 1500818269458 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50604
06:57:49 INFO - 1500818269459 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:49 INFO - 1500818269459 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269461 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269464 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b15c9131-57ee-4ebd-9f3d-f1257aa09ea5","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269465 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
06:57:49 INFO - 1500818269466 Marionette TRACE 386 <- [1,2,null,{}]
06:57:49 INFO - 1500818269468 Marionette DEBUG Closed connection 386
06:57:49 INFO - 1500818269469 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50605
06:57:49 INFO - 1500818269469 Marionette DEBUG Closed connection 387
06:57:49 INFO - 1500818269470 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50606
06:57:49 INFO - 1500818269471 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:57:49 INFO - 1500818269471 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269473 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269476 Marionette TRACE 388 <- [1,1,null,{"sessionId":"bb6320bf-ffd3-4520-b0af-bf2450d0eb16","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269477 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
06:57:49 INFO - 1500818269478 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"
06:57:49 INFO - 1500818269481 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"
06:57:49 INFO - 1500818269482 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"
06:57:49 INFO - 1500818269489 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:57:49 INFO - 1500818269495 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:57:49 INFO - 1500818269500 Marionette TRACE 388 <- [1,2,null,{}]
06:57:49 INFO - 1500818269503 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
06:57:49 INFO - 1500818269506 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f52673f-ca7b-4ccb-8dcb-16e45ecb7a9a","ELEMENT":"7f52673f-ca7b-4ccb-8dcb-16e45ecb7a9a"}}]
06:57:49 INFO - 1500818269507 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"7f52673f-ca7b-4ccb-8dcb-16e45ecb7a9a"}]
06:57:49 INFO - 1500818269532 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:57:49 INFO - 1500818269534 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
06:57:49 INFO - TEST-END: test_click.py TestClick.test_input_file1500818269536 Marionette TRACE 388 <- [1,5,null,{"value":null}]
06:57:49 INFO - 1500818269537 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
06:57:49 INFO - 1500818269538 Marionette TRACE 388 <- [1,6,null,{}]
06:57:49 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 103ms
06:57:49 INFO - TEST-START | test_click.py TestClick.test_obscured_element
06:57:49 INFO - 1500818269540 Marionette DEBUG Closed connection 388
06:57:49 INFO - 1500818269541 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50607
06:57:49 INFO - 1500818269541 Marionette DEBUG Closed connection 389
06:57:49 INFO - 1500818269542 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50608
06:57:49 INFO - 1500818269543 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:49 INFO - 1500818269544 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269546 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269549 Marionette TRACE 390 <- [1,1,null,{"sessionId":"f54c2680-e064-4c61-b8aa-c1cd43e01d2e","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269550 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
06:57:49 INFO - 1500818269550 Marionette TRACE 390 <- [1,2,null,{}]
06:57:49 INFO - 1500818269551 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:57:49 INFO - 1500818269551 Marionette TRACE 390 <- [1,3,null,{}]
06:57:49 INFO - 1500818269552 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
06:57:49 INFO - 1500818269553 Marionette TRACE 390 <- [1,4,null,{}]
06:57:49 INFO - 1500818269554 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
06:57:49 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500818269557 Marionette TRACE 390 <- [1,5,null,{"value":null}]
06:57:49 INFO - 1500818269558 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
06:57:49 INFO - 1500818269559 Marionette TRACE 390 <- [1,6,null,{}]
06:57:49 INFO - 1500818269560 Marionette DEBUG Closed connection 390
06:57:49 INFO - 1500818269561 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50609
06:57:49 INFO - 1500818269561 Marionette DEBUG Closed connection 391
06:57:49 INFO - 1500818269562 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50610
06:57:49 INFO - 1500818269563 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:49 INFO - 1500818269564 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269565 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269568 Marionette TRACE 392 <- [1,1,null,{"sessionId":"d7943065-41fb-42f3-ab1b-aaae11a08c24","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269569 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
06:57:49 INFO - 1500818269570 Marionette TRACE 392 <- [1,2,null,{}]
06:57:49 INFO - 1500818269572 Marionette DEBUG Closed connection 392
06:57:49 INFO - 1500818269572 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50611
06:57:49 INFO - 1500818269573 Marionette DEBUG Closed connection 393
06:57:49 INFO - 1500818269574 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50612
06:57:49 INFO - 1500818269575 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:57:49 INFO - 1500818269575 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269577 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269580 Marionette TRACE 394 <- [1,1,null,{"sessionId":"fdc32acf-b342-4d3c-b266-3d7af5dc793a","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269581 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
06:57:49 INFO - 1500818269582 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:57:49 INFO - 1500818269584 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:57:49 INFO - 1500818269584 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
06:57:49 INFO - 1500818269590 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"
06:57:49 INFO - 1500818269592 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"
06:57:49 INFO - 1500818269594 Marionette TRACE 394 <- [1,2,null,{}]
06:57:49 INFO - 1500818269603 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
06:57:49 INFO - 1500818269605 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a931ecc6-00f8-4293-918b-30a6c6173f5d","ELEMENT":"a931ecc6-00f8-4293-918b-30a6c6173f5d"}}]
06:57:49 INFO - 1500818269606 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
06:57:49 INFO - 1500818269608 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ec4d6f52-7d27-47eb-a1fa-b9c10f7c396c","ELEMENT":"ec4d6f52-7d27-47eb-a1fa-b9c10f7c396c"}}]
06:57:49 INFO - 1500818269609 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a931ecc6-00f8-4293-918b-30a6c6173f5d"}]
06:57:49 INFO - 1500818269849 Marionette DEBUG Canceled page load listener because no navigation has been detected
06:57:49 INFO - 1500818269850 Marionette TRACE 394 <- [1,5,null,{}]
06:57:49 INFO - 1500818269851 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"ec4d6f52-7d27-47eb-a1fa-b9c10f7c396c"}]
06:57:49 INFO - 1500818269865 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
06:57:49 INFO - 1500818269866 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
06:57:49 INFO - 1500818269869 Marionette TRACE 394 <- [1,7,null,{"value":false}]
06:57:49 INFO - 1500818269871 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
06:57:49 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500818269873 Marionette TRACE 394 <- [1,8,null,{"value":null}]
06:57:49 INFO - 1500818269874 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
06:57:49 INFO - 1500818269875 Marionette TRACE 394 <- [1,9,null,{}]
06:57:49 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 337ms
06:57:49 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
06:57:49 INFO - 1500818269876 Marionette DEBUG Closed connection 394
06:57:49 INFO - 1500818269878 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50613
06:57:49 INFO - 1500818269879 Marionette DEBUG Closed connection 395
06:57:49 INFO - 1500818269879 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50614
06:57:49 INFO - 1500818269880 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:49 INFO - 1500818269881 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269883 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269886 Marionette TRACE 396 <- [1,1,null,{"sessionId":"aa327629-1ef8-46fb-8fc3-5e3955615800","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269887 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
06:57:49 INFO - 1500818269888 Marionette TRACE 396 <- [1,2,null,{}]
06:57:49 INFO - 1500818269889 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
06:57:49 INFO - 1500818269889 Marionette TRACE 396 <- [1,3,null,{}]
06:57:49 INFO - 1500818269890 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
06:57:49 INFO - 1500818269890 Marionette TRACE 396 <- [1,4,null,{}]
06:57:49 INFO - 1500818269892 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
06:57:49 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500818269894 Marionette TRACE 396 <- [1,5,null,{"value":null}]
06:57:49 INFO - 1500818269895 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
06:57:49 INFO - 1500818269896 Marionette TRACE 396 <- [1,6,null,{}]
06:57:49 INFO - 1500818269897 Marionette DEBUG Closed connection 396
06:57:49 INFO - 1500818269898 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50615
06:57:49 INFO - 1500818269898 Marionette DEBUG Closed connection 397
06:57:49 INFO - 1500818269899 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50616
06:57:49 INFO - 1500818269900 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
06:57:49 INFO - 1500818269900 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269902 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269905 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a4b998c9-062e-4501-a7db-7cd792ec881d","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269906 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
06:57:49 INFO - 1500818269907 Marionette TRACE 398 <- [1,2,null,{}]
06:57:49 INFO - 1500818269909 Marionette DEBUG Closed connection 398
06:57:49 INFO - 1500818269909 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50617
06:57:49 INFO - 1500818269910 Marionette DEBUG Closed connection 399
06:57:49 INFO - 1500818269911 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50618
06:57:49 INFO - 1500818269912 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
06:57:49 INFO - 1500818269912 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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}
06:57:49 INFO - 1500818269914 Marionette DEBUG Register listener.js for window 4294967297
06:57:49 INFO - 1500818269917 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c6fe9789-e197-430b-b4cd-f695405078e6","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":2024,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpb3r2ay.mozrunner","moz:accessibilityChecks":false}}]
06:57:49 INFO - 1500818269918 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
06:57:49 INFO - 1500818269920 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"
06:57:49 INFO - 1500818269922 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"
06:57:49 INFO - 1500818269922 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"
06:57:49 INFO - 1500818269930 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"
06:57:49 INFO - 1500818269931 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"
06:57:49 INFO - 1500818269938 Marionette TRACE 400 <- [1,2,null,{}]
06:57:49 INFO - 1500818269941 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
06:57:49 INFO - 1500818269943 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4e70b885-1e7f-48ec-b768-605ff1efa964","ELEMENT":"4e70b885-1e7f-48ec-b768-605ff1efa964"}}]
06:57:49 INFO - 1500818269948 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"4e70b885-1e7f-48ec-b768-605ff1efa964"}]
06:57:49 INFO - 1500818269969 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
06:57:49 INFO - 1500818269970 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"4e70b885-1e7f-48ec-b768-605ff1efa964"}]
06:57:49 INFO - 1500818269985 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element