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]
16:52:08 INFO - 1498866728081 Marionette TRACE 358 -> [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}]
16:52:08 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498866728083 Marionette TRACE 358 <- [1,5,null,{"value":null}]
16:52:08 INFO - 1498866728084 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
16:52:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 137ms
16:52:08 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
16:52:08 INFO - 1498866728085 Marionette TRACE 358 <- [1,6,null,{}]
16:52:08 INFO - 1498866728099 Marionette DEBUG Closed connection 358
16:52:08 INFO - 1498866728100 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50031
16:52:08 INFO - 1498866728101 Marionette DEBUG Closed connection 359
16:52:08 INFO - 1498866728102 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50032
16:52:08 INFO - 1498866728103 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:08 INFO - 1498866728103 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728105 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728108 Marionette TRACE 360 <- [1,1,null,{"sessionId":"58cfeeca-b5ac-4d49-8ea5-74ad921d84ac","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728109 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
16:52:08 INFO - 1498866728110 Marionette TRACE 360 <- [1,2,null,{}]
16:52:08 INFO - 1498866728111 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:52:08 INFO - 1498866728111 Marionette TRACE 360 <- [1,3,null,{}]
16:52:08 INFO - 1498866728112 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
16:52:08 INFO - 1498866728112 Marionette TRACE 360 <- [1,4,null,{}]
16:52:08 INFO - 1498866728114 Marionette TRACE 360 -> [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}]
16:52:08 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498866728116 Marionette TRACE 360 <- [1,5,null,{"value":null}]
16:52:08 INFO - 1498866728117 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
16:52:08 INFO - 1498866728118 Marionette TRACE 360 <- [1,6,null,{}]
16:52:08 INFO - 1498866728120 Marionette DEBUG Closed connection 360
16:52:08 INFO - 1498866728120 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50033
16:52:08 INFO - 1498866728121 Marionette DEBUG Closed connection 361
16:52:08 INFO - 1498866728122 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50034
16:52:08 INFO - 1498866728123 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:08 INFO - 1498866728123 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728125 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728128 Marionette TRACE 362 <- [1,1,null,{"sessionId":"045b6398-b159-49e9-9f3b-48cdc6549094","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728129 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
16:52:08 INFO - 1498866728130 Marionette TRACE 362 <- [1,2,null,{}]
16:52:08 INFO - 1498866728131 Marionette DEBUG Closed connection 362
16:52:08 INFO - 1498866728132 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50035
16:52:08 INFO - 1498866728133 Marionette DEBUG Closed connection 363
16:52:08 INFO - 1498866728133 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50036
16:52:08 INFO - 1498866728134 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:52:08 INFO - 1498866728135 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728137 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728139 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1a5211c5-bb17-499d-b732-35584de0d191","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728140 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49634/clicks.html"}]
16:52:08 INFO - 1498866728142 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49634/hidden.html"
16:52:08 INFO - 1498866728147 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49634/hidden.html"
16:52:08 INFO - 1498866728147 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49634/hidden.html"
16:52:08 INFO - 1498866728163 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49634/clicks.html"
16:52:08 INFO - 1498866728168 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49634/clicks.html"
16:52:08 INFO - 1498866728182 Marionette TRACE 364 <- [1,2,null,{}]
16:52:08 INFO - 1498866728183 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
16:52:08 INFO - 1498866728187 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6cc2aff6-0e6a-442a-bdf9-e591432f9067","ELEMENT":"6cc2aff6-0e6a-442a-bdf9-e591432f9067"}}]
16:52:08 INFO - 1498866728188 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6cc2aff6-0e6a-442a-bdf9-e591432f9067"}]
16:52:08 INFO - 1498866728232 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49634/clicks.html"
16:52:08 INFO - 1498866728240 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49634/clicks.html"
16:52:08 INFO - 1498866728240 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49634/clicks.html"
16:52:08 INFO - 1498866728265 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49634/test.html"
16:52:08 INFO - 1498866728266 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49634/test.html"
16:52:08 INFO - 1498866728275 Marionette TRACE 364 <- [1,4,null,{}]
16:52:08 INFO - 1498866728286 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
16:52:08 INFO - 1498866728288 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21a95819-8ad2-4bf0-a2e3-38f58b124936","ELEMENT":"21a95819-8ad2-4bf0-a2e3-38f58b124936"}}]
16:52:08 INFO - 1498866728292 Marionette TRACE 364 -> [0,6,"getTitle",{}]
16:52:08 INFO - 1498866728293 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
16:52:08 INFO - 1498866728299 Marionette TRACE 364 -> [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}]
16:52:08 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498866728301 Marionette TRACE 364 <- [1,7,null,{"value":null}]
16:52:08 INFO - 1498866728302 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
16:52:08 INFO - 1498866728303 Marionette TRACE 364 <- [1,8,null,{}]
16:52:08 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 218ms
16:52:08 INFO - TEST-START | test_click.py TestClick.test_container_element
16:52:08 INFO - 1498866728305 Marionette DEBUG Closed connection 364
16:52:08 INFO - 1498866728306 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50039
16:52:08 INFO - 1498866728307 Marionette DEBUG Closed connection 365
16:52:08 INFO - 1498866728308 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50040
16:52:08 INFO - 1498866728309 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:08 INFO - 1498866728309 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728311 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728314 Marionette TRACE 366 <- [1,1,null,{"sessionId":"62888607-8985-4354-9145-0b46fdc2d5c6","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728315 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
16:52:08 INFO - 1498866728315 Marionette TRACE 366 <- [1,2,null,{}]
16:52:08 INFO - 1498866728316 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:52:08 INFO - 1498866728317 Marionette TRACE 366 <- [1,3,null,{}]
16:52:08 INFO - 1498866728317 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
16:52:08 INFO - 1498866728318 Marionette TRACE 366 <- [1,4,null,{}]
16:52:08 INFO - 1498866728319 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')","sandbox":"simpletest","line":316}]
16:52:08 INFO - TEST-START: test_click.py TestClick.test_container_element1498866728322 Marionette TRACE 366 <- [1,5,null,{"value":null}]
16:52:08 INFO - 1498866728322 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
16:52:08 INFO - 1498866728323 Marionette TRACE 366 <- [1,6,null,{}]
16:52:08 INFO - 1498866728325 Marionette DEBUG Closed connection 366
16:52:08 INFO - 1498866728326 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50041
16:52:08 INFO - 1498866728326 Marionette DEBUG Closed connection 367
16:52:08 INFO - 1498866728327 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50042
16:52:08 INFO - 1498866728328 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:08 INFO - 1498866728329 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728330 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728333 Marionette TRACE 368 <- [1,1,null,{"sessionId":"79a9f639-a535-4960-8c47-f34923b91dc9","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728334 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
16:52:08 INFO - 1498866728335 Marionette TRACE 368 <- [1,2,null,{}]
16:52:08 INFO - 1498866728337 Marionette DEBUG Closed connection 368
16:52:08 INFO - 1498866728337 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50043
16:52:08 INFO - 1498866728338 Marionette DEBUG Closed connection 369
16:52:08 INFO - 1498866728339 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50044
16:52:08 INFO - 1498866728340 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:52:08 INFO - 1498866728340 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728342 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728345 Marionette TRACE 370 <- [1,1,null,{"sessionId":"64b1ddd4-6133-4170-94af-99a9a1141a4b","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728346 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%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"}]
16:52:08 INFO - 1498866728347 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49634/test.html"
16:52:08 INFO - 1498866728349 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49634/test.html"
16:52:08 INFO - 1498866728349 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49634/test.html"
16:52:08 INFO - 1498866728371 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"
16:52:08 INFO - 1498866728373 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"
16:52:08 INFO - 1498866728376 Marionette TRACE 370 <- [1,2,null,{}]
16:52:08 INFO - 1498866728378 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
16:52:08 INFO - 1498866728380 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6c730438-7e6d-4de8-a176-9b04a7de7d6b","ELEMENT":"6c730438-7e6d-4de8-a176-9b04a7de7d6b"}}]
16:52:08 INFO - 1498866728381 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"6c730438-7e6d-4de8-a176-9b04a7de7d6b"}]
16:52:08 INFO - 1498866728619 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:52:08 INFO - 1498866728620 Marionette TRACE 370 <- [1,4,null,{}]
16:52:08 INFO - 1498866728621 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"6c730438-7e6d-4de8-a176-9b04a7de7d6b","name":"selected"}]
16:52:08 INFO - 1498866728625 Marionette TRACE 370 <- [1,5,null,{"value":true}]
16:52:08 INFO - 1498866728626 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')","sandbox":"simpletest","line":328}]
16:52:08 INFO - TEST-END: test_click.py TestClick.test_container_element1498866728629 Marionette TRACE 370 <- [1,6,null,{"value":null}]
16:52:08 INFO - 1498866728630 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
16:52:08 INFO - 1498866728631 Marionette TRACE 370 <- [1,7,null,{}]
16:52:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 327ms
16:52:08 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
16:52:08 INFO - 1498866728632 Marionette DEBUG Closed connection 370
16:52:08 INFO - 1498866728634 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50045
16:52:08 INFO - 1498866728634 Marionette DEBUG Closed connection 371
16:52:08 INFO - 1498866728635 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50046
16:52:08 INFO - 1498866728636 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:08 INFO - 1498866728637 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728639 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728641 Marionette TRACE 372 <- [1,1,null,{"sessionId":"34db17a1-e523-45b1-8a61-246feca443ea","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728642 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
16:52:08 INFO - 1498866728643 Marionette TRACE 372 <- [1,2,null,{}]
16:52:08 INFO - 1498866728644 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:52:08 INFO - 1498866728644 Marionette TRACE 372 <- [1,3,null,{}]
16:52:08 INFO - 1498866728645 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
16:52:08 INFO - 1498866728645 Marionette TRACE 372 <- [1,4,null,{}]
16:52:08 INFO - 1498866728647 Marionette TRACE 372 -> [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}]
16:52:08 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498866728649 Marionette TRACE 372 <- [1,5,null,{"value":null}]
16:52:08 INFO - 1498866728650 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
16:52:08 INFO - 1498866728651 Marionette TRACE 372 <- [1,6,null,{}]
16:52:08 INFO - 1498866728653 Marionette DEBUG Closed connection 372
16:52:08 INFO - 1498866728654 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50047
16:52:08 INFO - 1498866728654 Marionette DEBUG Closed connection 373
16:52:08 INFO - 1498866728655 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50048
16:52:08 INFO - 1498866728656 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:08 INFO - 1498866728656 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728658 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728661 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f327dcc8-98c5-493c-aee1-a9e13dc78c5d","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728662 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
16:52:08 INFO - 1498866728663 Marionette TRACE 374 <- [1,2,null,{}]
16:52:08 INFO - 1498866728664 Marionette DEBUG Closed connection 374
16:52:08 INFO - 1498866728665 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50049
16:52:08 INFO - 1498866728666 Marionette DEBUG Closed connection 375
16:52:08 INFO - 1498866728667 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50050
16:52:08 INFO - 1498866728668 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:52:08 INFO - 1498866728668 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728670 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728673 Marionette TRACE 376 <- [1,1,null,{"sessionId":"44a6c3f4-17b6-40a7-9561-200fd3d5dff9","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728674 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%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"}]
16:52:08 INFO - 1498866728675 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"
16:52:08 INFO - 1498866728677 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"
16:52:08 INFO - 1498866728677 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"
16:52:08 INFO - 1498866728694 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"
16:52:08 INFO - 1498866728697 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"
16:52:08 INFO - 1498866728699 Marionette TRACE 376 <- [1,2,null,{}]
16:52:08 INFO - 1498866728706 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
16:52:08 INFO - 1498866728708 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dbf5abb7-ec2f-43ff-bb00-aa313d0dc577","ELEMENT":"dbf5abb7-ec2f-43ff-bb00-aa313d0dc577"}}]
16:52:08 INFO - 1498866728709 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"dbf5abb7-ec2f-43ff-bb00-aa313d0dc577"}]
16:52:08 INFO - 1498866728954 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:52:08 INFO - 1498866728955 Marionette TRACE 376 <- [1,4,null,{}]
16:52:08 INFO - 1498866728956 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"dbf5abb7-ec2f-43ff-bb00-aa313d0dc577","name":"selected"}]
16:52:08 INFO - 1498866728960 Marionette TRACE 376 <- [1,5,null,{"value":true}]
16:52:08 INFO - 1498866728961 Marionette TRACE 376 -> [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}]
16:52:08 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498866728964 Marionette TRACE 376 <- [1,6,null,{"value":null}]
16:52:08 INFO - 1498866728965 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
16:52:08 INFO - 1498866728966 Marionette TRACE 376 <- [1,7,null,{}]
16:52:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 335ms
16:52:08 INFO - TEST-START | test_click.py TestClick.test_css_transforms
16:52:08 INFO - 1498866728968 Marionette DEBUG Closed connection 376
16:52:08 INFO - 1498866728970 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50051
16:52:08 INFO - 1498866728970 Marionette DEBUG Closed connection 377
16:52:08 INFO - 1498866728971 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50052
16:52:08 INFO - 1498866728972 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:08 INFO - 1498866728972 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728974 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728977 Marionette TRACE 378 <- [1,1,null,{"sessionId":"f8ff7ed3-a85a-4c72-b9cd-855e5510ddb8","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:08 INFO - 1498866728978 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
16:52:08 INFO - 1498866728979 Marionette TRACE 378 <- [1,2,null,{}]
16:52:08 INFO - 1498866728979 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:52:08 INFO - 1498866728980 Marionette TRACE 378 <- [1,3,null,{}]
16:52:08 INFO - 1498866728981 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
16:52:08 INFO - 1498866728981 Marionette TRACE 378 <- [1,4,null,{}]
16:52:08 INFO - 1498866728983 Marionette TRACE 378 -> [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}]
16:52:08 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498866728985 Marionette TRACE 378 <- [1,5,null,{"value":null}]
16:52:08 INFO - 1498866728986 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
16:52:08 INFO - 1498866728987 Marionette TRACE 378 <- [1,6,null,{}]
16:52:08 INFO - 1498866728989 Marionette DEBUG Closed connection 378
16:52:08 INFO - 1498866728989 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50053
16:52:08 INFO - 1498866728990 Marionette DEBUG Closed connection 379
16:52:08 INFO - 1498866728991 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50054
16:52:08 INFO - 1498866728992 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:08 INFO - 1498866728992 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:08 INFO - 1498866728994 Marionette DEBUG Register listener.js for window 7
16:52:08 INFO - 1498866728997 Marionette TRACE 380 <- [1,1,null,{"sessionId":"38f6d488-2670-42ea-8da6-bb68ac66448e","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866728998 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
16:52:09 INFO - 1498866728999 Marionette TRACE 380 <- [1,2,null,{}]
16:52:09 INFO - 1498866729000 Marionette DEBUG Closed connection 380
16:52:09 INFO - 1498866729001 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50055
16:52:09 INFO - 1498866729001 Marionette DEBUG Closed connection 381
16:52:09 INFO - 1498866729002 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50056
16:52:09 INFO - 1498866729003 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:52:09 INFO - 1498866729004 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729006 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729008 Marionette TRACE 382 <- [1,1,null,{"sessionId":"1e339a8d-8282-42b0-92ee-4fe65d7ef5f4","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729009 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%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"}]
16:52:09 INFO - 1498866729010 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"
16:52:09 INFO - 1498866729012 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"
16:52:09 INFO - 1498866729013 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"
16:52:09 INFO - 1498866729031 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"
16:52:09 INFO - 1498866729034 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"
16:52:09 INFO - 1498866729043 Marionette TRACE 382 <- [1,2,null,{}]
16:52:09 INFO - 1498866729044 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
16:52:09 INFO - 1498866729046 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ffa7a6dc-aab7-44f0-bf2c-45328b2e5595","ELEMENT":"ffa7a6dc-aab7-44f0-bf2c-45328b2e5595"}}]
16:52:09 INFO - 1498866729047 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"ffa7a6dc-aab7-44f0-bf2c-45328b2e5595"}]
16:52:09 INFO - 1498866729281 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:52:09 INFO - 1498866729282 Marionette TRACE 382 <- [1,4,null,{}]
16:52:09 INFO - 1498866729283 Marionette TRACE 382 -> [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}]
16:52:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498866729286 Marionette TRACE 382 <- [1,5,null,{"value":null}]
16:52:09 INFO - 1498866729287 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
16:52:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 322ms
16:52:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
16:52:09 INFO - 1498866729288 Marionette TRACE 382 <- [1,6,null,{}]
16:52:09 INFO - 1498866729290 Marionette DEBUG Closed connection 382
16:52:09 INFO - 1498866729291 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50057
16:52:09 INFO - 1498866729292 Marionette DEBUG Closed connection 383
16:52:09 INFO - 1498866729292 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50058
16:52:09 INFO - 1498866729293 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:09 INFO - 1498866729294 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729296 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729298 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e7bfeac9-7556-408e-b4ac-e3ae13bce659","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729299 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
16:52:09 INFO - 1498866729300 Marionette TRACE 384 <- [1,2,null,{}]
16:52:09 INFO - 1498866729301 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:52:09 INFO - 1498866729301 Marionette TRACE 384 <- [1,3,null,{}]
16:52:09 INFO - 1498866729302 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
16:52:09 INFO - 1498866729302 Marionette TRACE 384 <- [1,4,null,{}]
16:52:09 INFO - 1498866729304 Marionette TRACE 384 -> [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}]
16:52:09 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498866729306 Marionette TRACE 384 <- [1,5,null,{"value":null}]
16:52:09 INFO - 1498866729307 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
16:52:09 INFO - 1498866729308 Marionette TRACE 384 <- [1,6,null,{}]
16:52:09 INFO - 1498866729310 Marionette DEBUG Closed connection 384
16:52:09 INFO - 1498866729311 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50059
16:52:09 INFO - 1498866729311 Marionette DEBUG Closed connection 385
16:52:09 INFO - 1498866729312 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50060
16:52:09 INFO - 1498866729313 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:09 INFO - 1498866729313 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729315 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729318 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1cb35c6b-4215-4ca1-8362-4db1d35fa72f","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729319 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
16:52:09 INFO - 1498866729320 Marionette TRACE 386 <- [1,2,null,{}]
16:52:09 INFO - 1498866729322 Marionette DEBUG Closed connection 386
16:52:09 INFO - 1498866729322 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50061
16:52:09 INFO - 1498866729323 Marionette DEBUG Closed connection 387
16:52:09 INFO - 1498866729324 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50062
16:52:09 INFO - 1498866729325 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:52:09 INFO - 1498866729325 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729327 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729330 Marionette TRACE 388 <- [1,1,null,{"sessionId":"81b671b3-33ff-4ab5-a5c2-45183ef1444c","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729331 Marionette TRACE 388 -> [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"}]
16:52:09 INFO - 1498866729332 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"
16:52:09 INFO - 1498866729334 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"
16:52:09 INFO - 1498866729334 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"
16:52:09 INFO - 1498866729348 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"
16:52:09 INFO - 1498866729354 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"
16:52:09 INFO - 1498866729357 Marionette TRACE 388 <- [1,2,null,{}]
16:52:09 INFO - 1498866729358 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
16:52:09 INFO - 1498866729360 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"846129ca-0135-4753-8690-4704f56feb84","ELEMENT":"846129ca-0135-4753-8690-4704f56feb84"}}]
16:52:09 INFO - 1498866729361 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"846129ca-0135-4753-8690-4704f56feb84"}]
16:52:09 INFO - 1498866729617 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:52:09 INFO - 1498866729618 Marionette TRACE 388 <- [1,4,null,{}]
16:52:09 INFO - 1498866729621 Marionette TRACE 388 -> [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}]
16:52:09 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498866729624 Marionette TRACE 388 <- [1,5,null,{"value":null}]
16:52:09 INFO - 1498866729626 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
16:52:09 INFO - 1498866729628 Marionette TRACE 388 <- [1,6,null,{}]
16:52:09 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 340ms
16:52:09 INFO - TEST-START | test_click.py TestClick.test_input_file
16:52:09 INFO - 1498866729631 Marionette DEBUG Closed connection 388
16:52:09 INFO - 1498866729632 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50063
16:52:09 INFO - 1498866729642 Marionette DEBUG Closed connection 389
16:52:09 INFO - 1498866729642 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50064
16:52:09 INFO - 1498866729644 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:09 INFO - 1498866729647 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729649 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729655 Marionette TRACE 390 <- [1,1,null,{"sessionId":"fe2ef4e9-b3aa-4728-ae77-e56939cbcce4","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729656 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
16:52:09 INFO - 1498866729658 Marionette TRACE 390 <- [1,2,null,{}]
16:52:09 INFO - 1498866729728 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:52:09 INFO - 1498866729729 Marionette TRACE 390 <- [1,3,null,{}]
16:52:09 INFO - 1498866729730 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
16:52:09 INFO - 1498866729732 Marionette TRACE 390 <- [1,4,null,{}]
16:52:09 INFO - 1498866729737 Marionette TRACE 390 -> [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}]
16:52:09 INFO - TEST-START: test_click.py TestClick.test_input_file1498866729741 Marionette TRACE 390 <- [1,5,null,{"value":null}]
16:52:09 INFO - 1498866729742 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
16:52:09 INFO - 1498866729744 Marionette TRACE 390 <- [1,6,null,{}]
16:52:09 INFO - 1498866729747 Marionette DEBUG Closed connection 390
16:52:09 INFO - 1498866729748 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50065
16:52:09 INFO - 1498866729750 Marionette DEBUG Closed connection 391
16:52:09 INFO - 1498866729751 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50066
16:52:09 INFO - 1498866729752 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:09 INFO - 1498866729754 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729757 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729762 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a071d357-4916-4922-80b1-cd1c83cdb629","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729764 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
16:52:09 INFO - 1498866729765 Marionette TRACE 392 <- [1,2,null,{}]
16:52:09 INFO - 1498866729767 Marionette DEBUG Closed connection 392
16:52:09 INFO - 1498866729769 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50067
16:52:09 INFO - 1498866729770 Marionette DEBUG Closed connection 393
16:52:09 INFO - 1498866729770 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50068
16:52:09 INFO - 1498866729772 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:52:09 INFO - 1498866729773 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729775 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729780 Marionette TRACE 394 <- [1,1,null,{"sessionId":"af895b3e-72fa-458c-bc28-6af64163d414","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729781 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
16:52:09 INFO - 1498866729783 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"
16:52:09 INFO - 1498866729787 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"
16:52:09 INFO - 1498866729788 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"
16:52:09 INFO - 1498866729810 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:52:09 INFO - 1498866729815 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:52:09 INFO - 1498866729819 Marionette TRACE 394 <- [1,2,null,{}]
16:52:09 INFO - 1498866729828 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
16:52:09 INFO - 1498866729831 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"39582e8a-dcf2-462a-8772-4dfcc297b59a","ELEMENT":"39582e8a-dcf2-462a-8772-4dfcc297b59a"}}]
16:52:09 INFO - 1498866729832 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"39582e8a-dcf2-462a-8772-4dfcc297b59a"}]
16:52:09 INFO - 1498866729871 Marionette TRACE 394 <- [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]
16:52:09 INFO - 1498866729876 Marionette TRACE 394 -> [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}]
16:52:09 INFO - TEST-END: test_click.py TestClick.test_input_file1498866729881 Marionette TRACE 394 <- [1,5,null,{"value":null}]
16:52:09 INFO - 1498866729882 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
16:52:09 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 255ms
16:52:09 INFO - 1498866729884 Marionette TRACE 394 <- [1,6,null,{}]
16:52:09 INFO - TEST-START | test_click.py TestClick.test_obscured_element
16:52:09 INFO - 1498866729886 Marionette DEBUG Closed connection 394
16:52:09 INFO - 1498866729887 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50069
16:52:09 INFO - 1498866729888 Marionette DEBUG Closed connection 395
16:52:09 INFO - 1498866729889 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50070
16:52:09 INFO - 1498866729890 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:09 INFO - 1498866729891 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729893 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729897 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a7bc1716-2029-42ad-9f2e-16964bc94fb9","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729898 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
16:52:09 INFO - 1498866729898 Marionette TRACE 396 <- [1,2,null,{}]
16:52:09 INFO - 1498866729899 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:52:09 INFO - 1498866729900 Marionette TRACE 396 <- [1,3,null,{}]
16:52:09 INFO - 1498866729901 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
16:52:09 INFO - 1498866729901 Marionette TRACE 396 <- [1,4,null,{}]
16:52:09 INFO - 1498866729903 Marionette TRACE 396 -> [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}]
16:52:09 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498866729906 Marionette TRACE 396 <- [1,5,null,{"value":null}]
16:52:09 INFO - 1498866729907 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
16:52:09 INFO - 1498866729908 Marionette TRACE 396 <- [1,6,null,{}]
16:52:09 INFO - 1498866729910 Marionette DEBUG Closed connection 396
16:52:09 INFO - 1498866729910 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50071
16:52:09 INFO - 1498866729911 Marionette DEBUG Closed connection 397
16:52:09 INFO - 1498866729912 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50072
16:52:09 INFO - 1498866729913 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:09 INFO - 1498866729914 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729916 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729920 Marionette TRACE 398 <- [1,1,null,{"sessionId":"d54b07f8-40fa-4f81-b6fc-d6534426bec7","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729921 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
16:52:09 INFO - 1498866729922 Marionette TRACE 398 <- [1,2,null,{}]
16:52:09 INFO - 1498866729924 Marionette DEBUG Closed connection 398
16:52:09 INFO - 1498866729925 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50073
16:52:09 INFO - 1498866729926 Marionette DEBUG Closed connection 399
16:52:09 INFO - 1498866729926 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50074
16:52:09 INFO - 1498866729928 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:52:09 INFO - 1498866729929 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:09 INFO - 1498866729930 Marionette DEBUG Register listener.js for window 7
16:52:09 INFO - 1498866729935 Marionette TRACE 400 <- [1,1,null,{"sessionId":"648a2593-aeae-4b10-b559-9651b5e3793f","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:09 INFO - 1498866729936 Marionette TRACE 400 -> [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"}]
16:52:09 INFO - 1498866729938 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:52:09 INFO - 1498866729941 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:52:09 INFO - 1498866729941 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
16:52:09 INFO - 1498866729963 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"
16:52:09 INFO - 1498866729965 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"
16:52:09 INFO - 1498866729969 Marionette TRACE 400 <- [1,2,null,{}]
16:52:09 INFO - 1498866729987 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
16:52:09 INFO - 1498866729989 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"154f3032-2662-4f50-9352-0abb4100207c","ELEMENT":"154f3032-2662-4f50-9352-0abb4100207c"}}]
16:52:09 INFO - 1498866729990 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
16:52:09 INFO - 1498866729993 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"697154cf-1d52-4c4f-9f1f-e93bb87f498a","ELEMENT":"697154cf-1d52-4c4f-9f1f-e93bb87f498a"}}]
16:52:09 INFO - 1498866729994 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"154f3032-2662-4f50-9352-0abb4100207c"}]
16:52:10 INFO - 1498866730237 Marionette DEBUG Canceled page load listener because no navigation has been detected
16:52:10 INFO - 1498866730238 Marionette TRACE 400 <- [1,5,null,{}]
16:52:10 INFO - 1498866730246 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"697154cf-1d52-4c4f-9f1f-e93bb87f498a"}]
16:52:10 INFO - 1498866730269 Marionette TRACE 400 <- [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]
16:52:10 INFO - 1498866730271 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
16:52:10 INFO - 1498866730274 Marionette TRACE 400 <- [1,7,null,{"value":false}]
16:52:10 INFO - 1498866730276 Marionette TRACE 400 -> [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}]
16:52:10 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498866730278 Marionette TRACE 400 <- [1,8,null,{"value":null}]
16:52:10 INFO - 1498866730279 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
16:52:10 INFO - 1498866730280 Marionette TRACE 400 <- [1,9,null,{}]
16:52:10 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 396ms
16:52:10 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
16:52:10 INFO - 1498866730283 Marionette DEBUG Closed connection 400
16:52:10 INFO - 1498866730285 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50075
16:52:10 INFO - 1498866730287 Marionette DEBUG Closed connection 401
16:52:10 INFO - 1498866730288 Marionette DEBUG Accepted connection 402 from 127.0.0.1:50076
16:52:10 INFO - 1498866730289 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:10 INFO - 1498866730290 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:10 INFO - 1498866730293 Marionette DEBUG Register listener.js for window 7
16:52:10 INFO - 1498866730296 Marionette TRACE 402 <- [1,1,null,{"sessionId":"54242842-0852-4594-960d-7b549900891e","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:10 INFO - 1498866730297 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
16:52:10 INFO - 1498866730298 Marionette TRACE 402 <- [1,2,null,{}]
16:52:10 INFO - 1498866730299 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
16:52:10 INFO - 1498866730299 Marionette TRACE 402 <- [1,3,null,{}]
16:52:10 INFO - 1498866730300 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
16:52:10 INFO - 1498866730300 Marionette TRACE 402 <- [1,4,null,{}]
16:52:10 INFO - 1498866730302 Marionette TRACE 402 -> [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}]
16:52:10 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498866730305 Marionette TRACE 402 <- [1,5,null,{"value":null}]
16:52:10 INFO - 1498866730306 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
16:52:10 INFO - 1498866730307 Marionette TRACE 402 <- [1,6,null,{}]
16:52:10 INFO - 1498866730309 Marionette DEBUG Closed connection 402
16:52:10 INFO - 1498866730309 Marionette DEBUG Accepted connection 403 from 127.0.0.1:50077
16:52:10 INFO - 1498866730310 Marionette DEBUG Closed connection 403
16:52:10 INFO - 1498866730311 Marionette DEBUG Accepted connection 404 from 127.0.0.1:50078
16:52:10 INFO - 1498866730312 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
16:52:10 INFO - 1498866730312 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:10 INFO - 1498866730314 Marionette DEBUG Register listener.js for window 7
16:52:10 INFO - 1498866730317 Marionette TRACE 404 <- [1,1,null,{"sessionId":"067d7a74-9d6d-433a-a610-043d1fcd360e","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:10 INFO - 1498866730318 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
16:52:10 INFO - 1498866730319 Marionette TRACE 404 <- [1,2,null,{}]
16:52:10 INFO - 1498866730321 Marionette DEBUG Closed connection 404
16:52:10 INFO - 1498866730322 Marionette DEBUG Accepted connection 405 from 127.0.0.1:50079
16:52:10 INFO - 1498866730339 Marionette DEBUG Closed connection 405
16:52:10 INFO - 1498866730340 Marionette DEBUG Accepted connection 406 from 127.0.0.1:50080
16:52:10 INFO - 1498866730342 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
16:52:10 INFO - 1498866730343 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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}
16:52:10 INFO - 1498866730345 Marionette DEBUG Register listener.js for window 7
16:52:10 INFO - 1498866730348 Marionette TRACE 406 <- [1,1,null,{"sessionId":"bf5729ea-55d0-43b0-b1f0-fa23a1312982","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":2844,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpsysn1g.mozrunner","moz:accessibilityChecks":false}}]
16:52:10 INFO - 1498866730349 Marionette TRACE 406 -> [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"}]
16:52:10 INFO - 1498866730351 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"
16:52:10 INFO - 1498866730354 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"
16:52:10 INFO - 1498866730354 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"
16:52:10 INFO - 1498866730367 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"
16:52:10 INFO - 1498866730371 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"
16:52:10 INFO - 1498866730380 Marionette TRACE 406 <- [1,2,null,{}]
16:52:10 INFO - 1498866730381 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
16:52:10 INFO - 1498866730384 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c2827d22-ef38-4d65-84f4-eab3a36fd208","ELEMENT":"c2827d22-ef38-4d65-84f4-eab3a36fd208"}}]
16:52:10 INFO - 1498866730415 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"c2827d22-ef38-4d65-84f4-eab3a36fd208"}]
16:52:10 INFO - 1498866730437 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
16:52:10 INFO - 1498866730438 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"c2827d22-ef38-4d65-84f4-eab3a36fd208"}]
16:52:10 INFO - 1498866730457 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element