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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:13:31 INFO - 1500394411024 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}]
09:13:31 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500394411028 Marionette TRACE 352 <- [1,5,null,{"value":null}]
09:13:31 INFO - 1500394411029 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
09:13:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 157ms
09:13:31 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
09:13:31 INFO - 1500394411031 Marionette TRACE 352 <- [1,6,null,{}]
09:13:31 INFO - 1500394411034 Marionette DEBUG Closed connection 352
09:13:31 INFO - 1500394411035 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49870
09:13:31 INFO - 1500394411036 Marionette DEBUG Closed connection 353
09:13:31 INFO - 1500394411037 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49871
09:13:31 INFO - 1500394411038 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:31 INFO - 1500394411038 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411041 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411045 Marionette TRACE 354 <- [1,1,null,{"sessionId":"be4a18e3-4b9a-451a-a165-0d5a14edc5f0","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411047 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
09:13:31 INFO - 1500394411048 Marionette TRACE 354 <- [1,2,null,{}]
09:13:31 INFO - 1500394411049 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:13:31 INFO - 1500394411049 Marionette TRACE 354 <- [1,3,null,{}]
09:13:31 INFO - 1500394411050 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
09:13:31 INFO - 1500394411051 Marionette TRACE 354 <- [1,4,null,{}]
09:13:31 INFO - 1500394411052 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}]
09:13:31 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500394411055 Marionette TRACE 354 <- [1,5,null,{"value":null}]
09:13:31 INFO - 1500394411056 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
09:13:31 INFO - 1500394411058 Marionette TRACE 354 <- [1,6,null,{}]
09:13:31 INFO - 1500394411060 Marionette DEBUG Closed connection 354
09:13:31 INFO - 1500394411061 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49872
09:13:31 INFO - 1500394411062 Marionette DEBUG Closed connection 355
09:13:31 INFO - 1500394411063 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49873
09:13:31 INFO - 1500394411064 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:31 INFO - 1500394411065 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411075 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411079 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5164d95a-ef51-4f6c-a9f6-a61ad3f6f396","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411081 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
09:13:31 INFO - 1500394411083 Marionette TRACE 356 <- [1,2,null,{}]
09:13:31 INFO - 1500394411086 Marionette DEBUG Closed connection 356
09:13:31 INFO - 1500394411086 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49874
09:13:31 INFO - 1500394411087 Marionette DEBUG Closed connection 357
09:13:31 INFO - 1500394411088 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49875
09:13:31 INFO - 1500394411089 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:13:31 INFO - 1500394411090 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411093 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411096 Marionette TRACE 358 <- [1,1,null,{"sessionId":"962ce654-b76c-407b-b249-73fcff5e0e8d","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411098 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49429/clicks.html"}]
09:13:31 INFO - 1500394411100 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49429/hidden.html"
09:13:31 INFO - 1500394411107 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49429/hidden.html"
09:13:31 INFO - 1500394411107 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49429/hidden.html"
09:13:31 INFO - 1500394411133 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49429/clicks.html"
09:13:31 INFO - 1500394411136 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49429/clicks.html"
09:13:31 INFO - 1500394411148 Marionette TRACE 358 <- [1,2,null,{}]
09:13:31 INFO - 1500394411153 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
09:13:31 INFO - 1500394411156 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"487deb6e-3969-4b20-bd68-67d0c8bfc6e2","ELEMENT":"487deb6e-3969-4b20-bd68-67d0c8bfc6e2"}}]
09:13:31 INFO - 1500394411157 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"487deb6e-3969-4b20-bd68-67d0c8bfc6e2"}]
09:13:31 INFO - 1500394411203 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49429/clicks.html"
09:13:31 INFO - 1500394411215 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49429/clicks.html"
09:13:31 INFO - 1500394411216 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49429/clicks.html"
09:13:31 INFO - 1500394411247 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49429/test.html"
09:13:31 INFO - 1500394411252 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49429/test.html"
09:13:31 INFO - 1500394411266 Marionette TRACE 358 <- [1,4,null,{}]
09:13:31 INFO - 1500394411268 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
09:13:31 INFO - 1500394411272 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e667f04-8b93-436a-be42-36de53c3939d","ELEMENT":"6e667f04-8b93-436a-be42-36de53c3939d"}}]
09:13:31 INFO - 1500394411274 Marionette TRACE 358 -> [0,6,"getTitle",{}]
09:13:31 INFO - 1500394411275 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
09:13:31 INFO - 1500394411276 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}]
09:13:31 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500394411280 Marionette TRACE 358 <- [1,7,null,{"value":null}]
09:13:31 INFO - 1500394411281 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
09:13:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 250ms
09:13:31 INFO - 1500394411282 Marionette TRACE 358 <- [1,8,null,{}]
09:13:31 INFO - TEST-START | test_click.py TestClick.test_container_element
09:13:31 INFO - 1500394411285 Marionette DEBUG Closed connection 358
09:13:31 INFO - 1500394411286 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49878
09:13:31 INFO - 1500394411287 Marionette DEBUG Closed connection 359
09:13:31 INFO - 1500394411288 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49879
09:13:31 INFO - 1500394411289 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:31 INFO - 1500394411290 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411292 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411296 Marionette TRACE 360 <- [1,1,null,{"sessionId":"1b51ea58-b7dd-48d9-8306-5f4a459ec522","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411298 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
09:13:31 INFO - 1500394411299 Marionette TRACE 360 <- [1,2,null,{}]
09:13:31 INFO - 1500394411300 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:13:31 INFO - 1500394411300 Marionette TRACE 360 <- [1,3,null,{}]
09:13:31 INFO - 1500394411301 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
09:13:31 INFO - 1500394411302 Marionette TRACE 360 <- [1,4,null,{}]
09:13:31 INFO - 1500394411304 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}]
09:13:31 INFO - TEST-START: test_click.py TestClick.test_container_element1500394411307 Marionette TRACE 360 <- [1,5,null,{"value":null}]
09:13:31 INFO - 1500394411308 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
09:13:31 INFO - 1500394411309 Marionette TRACE 360 <- [1,6,null,{}]
09:13:31 INFO - 1500394411312 Marionette DEBUG Closed connection 360
09:13:31 INFO - 1500394411312 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49880
09:13:31 INFO - 1500394411313 Marionette DEBUG Closed connection 361
09:13:31 INFO - 1500394411314 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49881
09:13:31 INFO - 1500394411315 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:31 INFO - 1500394411316 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411318 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411322 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4177fe24-96c7-4ae1-b4c2-80674ee32f45","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411324 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
09:13:31 INFO - 1500394411325 Marionette TRACE 362 <- [1,2,null,{}]
09:13:31 INFO - 1500394411328 Marionette DEBUG Closed connection 362
09:13:31 INFO - 1500394411328 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49882
09:13:31 INFO - 1500394411329 Marionette DEBUG Closed connection 363
09:13:31 INFO - 1500394411330 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49883
09:13:31 INFO - 1500394411331 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:13:31 INFO - 1500394411332 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411335 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411338 Marionette TRACE 364 <- [1,1,null,{"sessionId":"f43b24a5-b7f0-4ce6-b523-4e753c7b5788","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411341 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"}]
09:13:31 INFO - 1500394411343 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49429/test.html"
09:13:31 INFO - 1500394411346 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49429/test.html"
09:13:31 INFO - 1500394411346 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49429/test.html"
09:13:31 INFO - 1500394411373 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"
09:13:31 INFO - 1500394411378 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"
09:13:31 INFO - 1500394411382 Marionette TRACE 364 <- [1,2,null,{}]
09:13:31 INFO - 1500394411391 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:13:31 INFO - 1500394411394 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"599536aa-80e8-48b2-8cf3-6b3d2e25ae3d","ELEMENT":"599536aa-80e8-48b2-8cf3-6b3d2e25ae3d"}}]
09:13:31 INFO - 1500394411396 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"599536aa-80e8-48b2-8cf3-6b3d2e25ae3d"}]
09:13:31 INFO - 1500394411641 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:13:31 INFO - 1500394411642 Marionette TRACE 364 <- [1,4,null,{}]
09:13:31 INFO - 1500394411643 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"599536aa-80e8-48b2-8cf3-6b3d2e25ae3d","name":"selected"}]
09:13:31 INFO - 1500394411649 Marionette TRACE 364 <- [1,5,null,{"value":true}]
09:13:31 INFO - 1500394411651 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}]
09:13:31 INFO - TEST-END: test_click.py TestClick.test_container_element1500394411654 Marionette TRACE 364 <- [1,6,null,{"value":null}]
09:13:31 INFO - 1500394411655 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
09:13:31 INFO - 1500394411657 Marionette TRACE 364 <- [1,7,null,{}]
09:13:31 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 374ms
09:13:31 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
09:13:31 INFO - 1500394411659 Marionette DEBUG Closed connection 364
09:13:31 INFO - 1500394411660 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49884
09:13:31 INFO - 1500394411661 Marionette DEBUG Closed connection 365
09:13:31 INFO - 1500394411662 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49885
09:13:31 INFO - 1500394411663 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:31 INFO - 1500394411663 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411666 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411670 Marionette TRACE 366 <- [1,1,null,{"sessionId":"7d5b07ad-ce37-408f-89a8-d483b02e8bf2","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411672 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
09:13:31 INFO - 1500394411673 Marionette TRACE 366 <- [1,2,null,{}]
09:13:31 INFO - 1500394411673 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:13:31 INFO - 1500394411674 Marionette TRACE 366 <- [1,3,null,{}]
09:13:31 INFO - 1500394411675 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
09:13:31 INFO - 1500394411676 Marionette TRACE 366 <- [1,4,null,{}]
09:13:31 INFO - 1500394411678 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}]
09:13:31 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500394411681 Marionette TRACE 366 <- [1,5,null,{"value":null}]
09:13:31 INFO - 1500394411682 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
09:13:31 INFO - 1500394411683 Marionette TRACE 366 <- [1,6,null,{}]
09:13:31 INFO - 1500394411686 Marionette DEBUG Closed connection 366
09:13:31 INFO - 1500394411686 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49886
09:13:31 INFO - 1500394411687 Marionette DEBUG Closed connection 367
09:13:31 INFO - 1500394411688 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49887
09:13:31 INFO - 1500394411689 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:31 INFO - 1500394411690 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411693 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411711 Marionette TRACE 368 <- [1,1,null,{"sessionId":"b3489c73-a2f6-42f9-b1e8-eba47ecdfe10","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411714 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
09:13:31 INFO - 1500394411716 Marionette TRACE 368 <- [1,2,null,{}]
09:13:31 INFO - 1500394411718 Marionette DEBUG Closed connection 368
09:13:31 INFO - 1500394411719 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49888
09:13:31 INFO - 1500394411720 Marionette DEBUG Closed connection 369
09:13:31 INFO - 1500394411721 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49889
09:13:31 INFO - 1500394411722 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:13:31 INFO - 1500394411723 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:31 INFO - 1500394411725 Marionette DEBUG Register listener.js for window 15
09:13:31 INFO - 1500394411729 Marionette TRACE 370 <- [1,1,null,{"sessionId":"3d5e7eb1-d349-4128-8a0a-edf099eac953","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:31 INFO - 1500394411731 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"}]
09:13:31 INFO - 1500394411733 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"
09:13:31 INFO - 1500394411737 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"
09:13:31 INFO - 1500394411737 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"
09:13:31 INFO - 1500394411759 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"
09:13:31 INFO - 1500394411761 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"
09:13:31 INFO - 1500394411766 Marionette TRACE 370 <- [1,2,null,{}]
09:13:31 INFO - 1500394411773 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
09:13:31 INFO - 1500394411776 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cf8a88f6-7c9d-42d2-95c7-e8658715de85","ELEMENT":"cf8a88f6-7c9d-42d2-95c7-e8658715de85"}}]
09:13:31 INFO - 1500394411777 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"cf8a88f6-7c9d-42d2-95c7-e8658715de85"}]
09:13:32 INFO - 1500394412024 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:13:32 INFO - 1500394412025 Marionette TRACE 370 <- [1,4,null,{}]
09:13:32 INFO - 1500394412026 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"cf8a88f6-7c9d-42d2-95c7-e8658715de85","name":"selected"}]
09:13:32 INFO - 1500394412031 Marionette TRACE 370 <- [1,5,null,{"value":true}]
09:13:32 INFO - 1500394412033 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}]
09:13:32 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500394412036 Marionette TRACE 370 <- [1,6,null,{"value":null}]
09:13:32 INFO - 1500394412037 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
09:13:32 INFO - 1500394412039 Marionette TRACE 370 <- [1,7,null,{}]
09:13:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 382ms
09:13:32 INFO - TEST-START | test_click.py TestClick.test_css_transforms
09:13:32 INFO - 1500394412041 Marionette DEBUG Closed connection 370
09:13:32 INFO - 1500394412042 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49890
09:13:32 INFO - 1500394412043 Marionette DEBUG Closed connection 371
09:13:32 INFO - 1500394412044 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49891
09:13:32 INFO - 1500394412045 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:32 INFO - 1500394412045 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:32 INFO - 1500394412048 Marionette DEBUG Register listener.js for window 15
09:13:32 INFO - 1500394412052 Marionette TRACE 372 <- [1,1,null,{"sessionId":"f6ed7372-0cfb-4b73-a997-645d94c87ff9","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:32 INFO - 1500394412054 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
09:13:32 INFO - 1500394412054 Marionette TRACE 372 <- [1,2,null,{}]
09:13:32 INFO - 1500394412055 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:13:32 INFO - 1500394412056 Marionette TRACE 372 <- [1,3,null,{}]
09:13:32 INFO - 1500394412057 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
09:13:32 INFO - 1500394412057 Marionette TRACE 372 <- [1,4,null,{}]
09:13:32 INFO - 1500394412059 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}]
09:13:32 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500394412062 Marionette TRACE 372 <- [1,5,null,{"value":null}]
09:13:32 INFO - 1500394412063 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
09:13:32 INFO - 1500394412064 Marionette TRACE 372 <- [1,6,null,{}]
09:13:32 INFO - 1500394412067 Marionette DEBUG Closed connection 372
09:13:32 INFO - 1500394412068 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49892
09:13:32 INFO - 1500394412069 Marionette DEBUG Closed connection 373
09:13:32 INFO - 1500394412070 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49893
09:13:32 INFO - 1500394412071 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:32 INFO - 1500394412071 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:32 INFO - 1500394412074 Marionette DEBUG Register listener.js for window 15
09:13:32 INFO - 1500394412078 Marionette TRACE 374 <- [1,1,null,{"sessionId":"af49b449-2f40-41de-9752-b80827dc5a3d","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:32 INFO - 1500394412080 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
09:13:32 INFO - 1500394412082 Marionette TRACE 374 <- [1,2,null,{}]
09:13:32 INFO - 1500394412084 Marionette DEBUG Closed connection 374
09:13:32 INFO - 1500394412085 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49894
09:13:32 INFO - 1500394412086 Marionette DEBUG Closed connection 375
09:13:32 INFO - 1500394412086 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49895
09:13:32 INFO - 1500394412088 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:13:32 INFO - 1500394412088 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:32 INFO - 1500394412091 Marionette DEBUG Register listener.js for window 15
09:13:32 INFO - 1500394412094 Marionette TRACE 376 <- [1,1,null,{"sessionId":"73c299f3-9cbe-45d4-ba0f-98b934355354","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:32 INFO - 1500394412097 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"}]
09:13:32 INFO - 1500394412099 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"
09:13:32 INFO - 1500394412101 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"
09:13:32 INFO - 1500394412102 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"
09:13:32 INFO - 1500394412116 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"
09:13:32 INFO - 1500394412121 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"
09:13:32 INFO - 1500394412133 Marionette TRACE 376 <- [1,2,null,{}]
09:13:32 INFO - 1500394412135 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
09:13:32 INFO - 1500394412138 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1fdb1204-ef00-4436-8cbf-fe81cdc66b9e","ELEMENT":"1fdb1204-ef00-4436-8cbf-fe81cdc66b9e"}}]
09:13:32 INFO - 1500394412139 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"1fdb1204-ef00-4436-8cbf-fe81cdc66b9e"}]
09:13:32 INFO - 1500394412382 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:13:32 INFO - 1500394412384 Marionette TRACE 376 <- [1,4,null,{}]
09:13:32 INFO - 1500394412386 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}]
09:13:32 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500394412391 Marionette TRACE 376 <- [1,5,null,{"value":null}]
09:13:32 INFO - 1500394412393 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
09:13:32 INFO - 1500394412396 Marionette TRACE 376 <- [1,6,null,{}]
09:13:32 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 357ms
09:13:32 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
09:13:32 INFO - 1500394412399 Marionette DEBUG Closed connection 376
09:13:32 INFO - 1500394412400 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49896
09:13:32 INFO - 1500394412427 Marionette DEBUG Closed connection 377
09:13:32 INFO - 1500394412428 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49897
09:13:32 INFO - 1500394412431 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:32 INFO - 1500394412434 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:32 INFO - 1500394412437 Marionette DEBUG Register listener.js for window 15
09:13:32 INFO - 1500394412443 Marionette TRACE 378 <- [1,1,null,{"sessionId":"96e3c0fe-a126-452e-b8eb-afd8b80288af","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:32 INFO - 1500394412448 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
09:13:32 INFO - 1500394412449 Marionette TRACE 378 <- [1,2,null,{}]
09:13:32 INFO - 1500394412451 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:13:32 INFO - 1500394412452 Marionette TRACE 378 <- [1,3,null,{}]
09:13:32 INFO - 1500394412503 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
09:13:32 INFO - 1500394412505 Marionette TRACE 378 <- [1,4,null,{}]
09:13:32 INFO - 1500394412507 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}]
09:13:32 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500394412511 Marionette TRACE 378 <- [1,5,null,{"value":null}]
09:13:32 INFO - 1500394412513 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
09:13:32 INFO - 1500394412516 Marionette TRACE 378 <- [1,6,null,{}]
09:13:32 INFO - 1500394412534 Marionette DEBUG Closed connection 378
09:13:32 INFO - 1500394412535 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49898
09:13:32 INFO - 1500394412539 Marionette DEBUG Closed connection 379
09:13:32 INFO - 1500394412539 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49899
09:13:32 INFO - 1500394412542 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:32 INFO - 1500394412545 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:32 INFO - 1500394412548 Marionette DEBUG Register listener.js for window 15
09:13:32 INFO - 1500394412555 Marionette TRACE 380 <- [1,1,null,{"sessionId":"0a11a3eb-1910-4345-a378-a3c1a30ffb68","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:32 INFO - 1500394412559 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
09:13:32 INFO - 1500394412561 Marionette TRACE 380 <- [1,2,null,{}]
09:13:32 INFO - 1500394412564 Marionette DEBUG Closed connection 380
09:13:32 INFO - 1500394412566 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49900
09:13:32 INFO - 1500394412567 Marionette DEBUG Closed connection 381
09:13:32 INFO - 1500394412568 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49901
09:13:32 INFO - 1500394412569 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:13:32 INFO - 1500394412571 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:32 INFO - 1500394412574 Marionette DEBUG Register listener.js for window 15
09:13:32 INFO - 1500394412579 Marionette TRACE 382 <- [1,1,null,{"sessionId":"451c05be-91c7-4996-9fd0-f661302663a6","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:32 INFO - 1500394412582 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"}]
09:13:32 INFO - 1500394412585 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"
09:13:32 INFO - 1500394412590 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"
09:13:32 INFO - 1500394412590 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"
09:13:32 INFO - 1500394412616 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"
09:13:32 INFO - 1500394412624 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"
09:13:32 INFO - 1500394412630 Marionette TRACE 382 <- [1,2,null,{}]
09:13:32 INFO - 1500394412632 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
09:13:32 INFO - 1500394412644 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e3bef295-3143-44c1-a59f-f4ffa6f6b541","ELEMENT":"e3bef295-3143-44c1-a59f-f4ffa6f6b541"}}]
09:13:32 INFO - 1500394412645 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"e3bef295-3143-44c1-a59f-f4ffa6f6b541"}]
09:13:32 INFO - 1500394412897 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:13:32 INFO - 1500394412898 Marionette TRACE 382 <- [1,4,null,{}]
09:13:32 INFO - 1500394412900 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}]
09:13:32 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500394412905 Marionette TRACE 382 <- [1,5,null,{"value":null}]
09:13:32 INFO - 1500394412908 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
09:13:32 INFO - 1500394412911 Marionette TRACE 382 <- [1,6,null,{}]
09:13:32 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 515ms
09:13:32 INFO - TEST-START | test_click.py TestClick.test_input_file
09:13:32 INFO - 1500394412917 Marionette DEBUG Closed connection 382
09:13:32 INFO - 1500394412918 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49902
09:13:32 INFO - 1500394412924 Marionette DEBUG Closed connection 383
09:13:32 INFO - 1500394412925 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49903
09:13:32 INFO - 1500394412927 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:32 INFO - 1500394412928 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:32 INFO - 1500394412932 Marionette DEBUG Register listener.js for window 15
09:13:32 INFO - 1500394412937 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ba9c552a-f0f0-4b45-b0ba-8aa9b21f104b","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:32 INFO - 1500394412940 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
09:13:32 INFO - 1500394412940 Marionette TRACE 384 <- [1,2,null,{}]
09:13:32 INFO - 1500394412941 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:13:32 INFO - 1500394412942 Marionette TRACE 384 <- [1,3,null,{}]
09:13:32 INFO - 1500394412943 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
09:13:32 INFO - 1500394412944 Marionette TRACE 384 <- [1,4,null,{}]
09:13:32 INFO - 1500394412946 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}]
09:13:32 INFO - TEST-START: test_click.py TestClick.test_input_file1500394412949 Marionette TRACE 384 <- [1,5,null,{"value":null}]
09:13:32 INFO - 1500394412951 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
09:13:32 INFO - 1500394412952 Marionette TRACE 384 <- [1,6,null,{}]
09:13:32 INFO - 1500394412955 Marionette DEBUG Closed connection 384
09:13:32 INFO - 1500394412956 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49904
09:13:32 INFO - 1500394412957 Marionette DEBUG Closed connection 385
09:13:32 INFO - 1500394412975 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49905
09:13:32 INFO - 1500394412976 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:32 INFO - 1500394412977 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:32 INFO - 1500394412981 Marionette DEBUG Register listener.js for window 15
09:13:32 INFO - 1500394412985 Marionette TRACE 386 <- [1,1,null,{"sessionId":"01b7dff3-5747-49d6-b2a7-d7911637b864","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:32 INFO - 1500394412991 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
09:13:32 INFO - 1500394412993 Marionette TRACE 386 <- [1,2,null,{}]
09:13:32 INFO - 1500394412996 Marionette DEBUG Closed connection 386
09:13:32 INFO - 1500394412997 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49906
09:13:33 INFO - 1500394413031 Marionette DEBUG Closed connection 387
09:13:33 INFO - 1500394413032 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49907
09:13:33 INFO - 1500394413034 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:13:33 INFO - 1500394413036 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:33 INFO - 1500394413039 Marionette DEBUG Register listener.js for window 15
09:13:33 INFO - 1500394413045 Marionette TRACE 388 <- [1,1,null,{"sessionId":"6c166bc3-328d-4b0f-bbb4-8b85bffc140c","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:33 INFO - 1500394413047 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
09:13:33 INFO - 1500394413049 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"
09:13:33 INFO - 1500394413060 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"
09:13:33 INFO - 1500394413061 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"
09:13:33 INFO - 1500394413083 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:13:33 INFO - 1500394413090 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:13:33 INFO - 1500394413096 Marionette TRACE 388 <- [1,2,null,{}]
09:13:33 INFO - 1500394413097 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
09:13:33 INFO - 1500394413108 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8e237c4a-a089-44b6-9f03-fc47b4dc043e","ELEMENT":"8e237c4a-a089-44b6-9f03-fc47b4dc043e"}}]
09:13:33 INFO - 1500394413110 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"8e237c4a-a089-44b6-9f03-fc47b4dc043e"}]
09:13:33 INFO - 1500394413142 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:13:33 INFO - 1500394413144 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}]
09:13:33 INFO - TEST-END: test_click.py TestClick.test_input_file1500394413147 Marionette TRACE 388 <- [1,5,null,{"value":null}]
09:13:33 INFO - 1500394413148 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
09:13:33 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 238ms
09:13:33 INFO - 1500394413151 Marionette TRACE 388 <- [1,6,null,{}]
09:13:33 INFO - TEST-START | test_click.py TestClick.test_obscured_element
09:13:33 INFO - 1500394413154 Marionette DEBUG Closed connection 388
09:13:33 INFO - 1500394413156 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49908
09:13:33 INFO - 1500394413157 Marionette DEBUG Closed connection 389
09:13:33 INFO - 1500394413158 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49909
09:13:33 INFO - 1500394413159 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:33 INFO - 1500394413160 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:33 INFO - 1500394413163 Marionette DEBUG Register listener.js for window 15
09:13:33 INFO - 1500394413167 Marionette TRACE 390 <- [1,1,null,{"sessionId":"2f2986e9-6a8d-45c7-807f-49b298a1d904","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:33 INFO - 1500394413170 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
09:13:33 INFO - 1500394413170 Marionette TRACE 390 <- [1,2,null,{}]
09:13:33 INFO - 1500394413171 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:13:33 INFO - 1500394413172 Marionette TRACE 390 <- [1,3,null,{}]
09:13:33 INFO - 1500394413173 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
09:13:33 INFO - 1500394413174 Marionette TRACE 390 <- [1,4,null,{}]
09:13:33 INFO - 1500394413176 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}]
09:13:33 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500394413180 Marionette TRACE 390 <- [1,5,null,{"value":null}]
09:13:33 INFO - 1500394413181 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
09:13:33 INFO - 1500394413183 Marionette TRACE 390 <- [1,6,null,{}]
09:13:33 INFO - 1500394413186 Marionette DEBUG Closed connection 390
09:13:33 INFO - 1500394413187 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49910
09:13:33 INFO - 1500394413188 Marionette DEBUG Closed connection 391
09:13:33 INFO - 1500394413189 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49911
09:13:33 INFO - 1500394413194 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:33 INFO - 1500394413195 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:33 INFO - 1500394413198 Marionette DEBUG Register listener.js for window 15
09:13:33 INFO - 1500394413202 Marionette TRACE 392 <- [1,1,null,{"sessionId":"a4a9edf7-1c1c-4052-ada0-848819c3fe6a","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:33 INFO - 1500394413204 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
09:13:33 INFO - 1500394413206 Marionette TRACE 392 <- [1,2,null,{}]
09:13:33 INFO - 1500394413208 Marionette DEBUG Closed connection 392
09:13:33 INFO - 1500394413209 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49912
09:13:33 INFO - 1500394413210 Marionette DEBUG Closed connection 393
09:13:33 INFO - 1500394413211 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49913
09:13:33 INFO - 1500394413212 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:13:33 INFO - 1500394413213 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:33 INFO - 1500394413216 Marionette DEBUG Register listener.js for window 15
09:13:33 INFO - 1500394413220 Marionette TRACE 394 <- [1,1,null,{"sessionId":"a7ac5571-e79f-4099-90ea-082ea5960148","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:33 INFO - 1500394413224 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"}]
09:13:33 INFO - 1500394413226 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:13:33 INFO - 1500394413229 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:13:33 INFO - 1500394413229 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
09:13:33 INFO - 1500394413257 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"
09:13:33 INFO - 1500394413260 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"
09:13:33 INFO - 1500394413265 Marionette TRACE 394 <- [1,2,null,{}]
09:13:33 INFO - 1500394413266 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
09:13:33 INFO - 1500394413278 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"61a1e2ff-c8ce-4a0e-a0da-bf575d3c5c34","ELEMENT":"61a1e2ff-c8ce-4a0e-a0da-bf575d3c5c34"}}]
09:13:33 INFO - 1500394413279 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
09:13:33 INFO - 1500394413282 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"23cf6e4a-a51f-443e-982e-76bcb3fca4bd","ELEMENT":"23cf6e4a-a51f-443e-982e-76bcb3fca4bd"}}]
09:13:33 INFO - 1500394413299 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"61a1e2ff-c8ce-4a0e-a0da-bf575d3c5c34"}]
09:13:33 INFO - 1500394413543 Marionette DEBUG Canceled page load listener because no navigation has been detected
09:13:33 INFO - 1500394413544 Marionette TRACE 394 <- [1,5,null,{}]
09:13:33 INFO - 1500394413545 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"23cf6e4a-a51f-443e-982e-76bcb3fca4bd"}]
09:13:33 INFO - 1500394413565 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
09:13:33 INFO - 1500394413568 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
09:13:33 INFO - 1500394413572 Marionette TRACE 394 <- [1,7,null,{"value":false}]
09:13:33 INFO - 1500394413573 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}]
09:13:33 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500394413577 Marionette TRACE 394 <- [1,8,null,{"value":null}]
09:13:33 INFO - 1500394413578 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
09:13:33 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 428ms
09:13:33 INFO - 1500394413579 Marionette TRACE 394 <- [1,9,null,{}]
09:13:33 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
09:13:33 INFO - 1500394413582 Marionette DEBUG Closed connection 394
09:13:33 INFO - 1500394413583 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49914
09:13:33 INFO - 1500394413584 Marionette DEBUG Closed connection 395
09:13:33 INFO - 1500394413585 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49915
09:13:33 INFO - 1500394413586 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:33 INFO - 1500394413587 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:33 INFO - 1500394413589 Marionette DEBUG Register listener.js for window 15
09:13:33 INFO - 1500394413600 Marionette TRACE 396 <- [1,1,null,{"sessionId":"bdf97e51-5c3c-42b6-90c8-17e2d4b9ff91","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:33 INFO - 1500394413603 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
09:13:33 INFO - 1500394413604 Marionette TRACE 396 <- [1,2,null,{}]
09:13:33 INFO - 1500394413605 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
09:13:33 INFO - 1500394413605 Marionette TRACE 396 <- [1,3,null,{}]
09:13:33 INFO - 1500394413606 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
09:13:33 INFO - 1500394413607 Marionette TRACE 396 <- [1,4,null,{}]
09:13:33 INFO - 1500394413609 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}]
09:13:33 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500394413612 Marionette TRACE 396 <- [1,5,null,{"value":null}]
09:13:33 INFO - 1500394413613 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
09:13:33 INFO - 1500394413615 Marionette TRACE 396 <- [1,6,null,{}]
09:13:33 INFO - 1500394413617 Marionette DEBUG Closed connection 396
09:13:33 INFO - 1500394413618 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49916
09:13:33 INFO - 1500394413619 Marionette DEBUG Closed connection 397
09:13:33 INFO - 1500394413620 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49917
09:13:33 INFO - 1500394413621 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
09:13:33 INFO - 1500394413622 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:33 INFO - 1500394413625 Marionette DEBUG Register listener.js for window 15
09:13:33 INFO - 1500394413629 Marionette TRACE 398 <- [1,1,null,{"sessionId":"10c27cdf-076a-4f1b-bb8a-e43ec6f0b40c","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:33 INFO - 1500394413631 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
09:13:33 INFO - 1500394413633 Marionette TRACE 398 <- [1,2,null,{}]
09:13:33 INFO - 1500394413635 Marionette DEBUG Closed connection 398
09:13:33 INFO - 1500394413636 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49918
09:13:33 INFO - 1500394413637 Marionette DEBUG Closed connection 399
09:13:33 INFO - 1500394413638 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49919
09:13:33 INFO - 1500394413639 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
09:13:33 INFO - 1500394413640 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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}
09:13:33 INFO - 1500394413645 Marionette DEBUG Register listener.js for window 15
09:13:33 INFO - 1500394413650 Marionette TRACE 400 <- [1,1,null,{"sessionId":"da50a935-329e-42ea-8d7a-dfa05a06d5e1","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":1144,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpdtzg5k.mozrunner","moz:accessibilityChecks":false}}]
09:13:33 INFO - 1500394413652 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"}]
09:13:33 INFO - 1500394413654 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"
09:13:33 INFO - 1500394413658 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"
09:13:33 INFO - 1500394413659 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"
09:13:33 INFO - 1500394413681 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"
09:13:33 INFO - 1500394413685 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"
09:13:33 INFO - 1500394413698 Marionette TRACE 400 <- [1,2,null,{}]
09:13:33 INFO - 1500394413700 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
09:13:33 INFO - 1500394413704 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bd386da3-6718-4b1a-9b46-0ef0490813a2","ELEMENT":"bd386da3-6718-4b1a-9b46-0ef0490813a2"}}]
09:13:33 INFO - 1500394413706 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"bd386da3-6718-4b1a-9b46-0ef0490813a2"}]
09:13:33 INFO - 1500394413730 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
09:13:33 INFO - 1500394413731 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"bd386da3-6718-4b1a-9b46-0ef0490813a2"}]
09:13:33 INFO - 1500394413755 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element