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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
18:47:41 INFO - 1499737661150 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}]
18:47:41 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499737661155 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:47:41 INFO - 1499737661156 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:47:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 123ms
18:47:41 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:47:41 INFO - 1499737661157 Marionette TRACE 352 <- [1,6,null,{}]
18:47:41 INFO - 1499737661160 Marionette DEBUG Closed connection 352
18:47:41 INFO - 1499737661160 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50137
18:47:41 INFO - 1499737661161 Marionette DEBUG Closed connection 353
18:47:41 INFO - 1499737661162 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50138
18:47:41 INFO - 1499737661163 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:41 INFO - 1499737661164 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661166 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661169 Marionette TRACE 354 <- [1,1,null,{"sessionId":"be835fe2-b546-4c74-abc1-d9b44228a643","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661171 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:47:41 INFO - 1499737661171 Marionette TRACE 354 <- [1,2,null,{}]
18:47:41 INFO - 1499737661172 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:47:41 INFO - 1499737661172 Marionette TRACE 354 <- [1,3,null,{}]
18:47:41 INFO - 1499737661173 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:47:41 INFO - 1499737661174 Marionette TRACE 354 <- [1,4,null,{}]
18:47:41 INFO - 1499737661175 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}]
18:47:41 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499737661179 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:47:41 INFO - 1499737661180 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:47:41 INFO - 1499737661181 Marionette TRACE 354 <- [1,6,null,{}]
18:47:41 INFO - 1499737661182 Marionette DEBUG Closed connection 354
18:47:41 INFO - 1499737661183 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50139
18:47:41 INFO - 1499737661184 Marionette DEBUG Closed connection 355
18:47:41 INFO - 1499737661185 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50140
18:47:41 INFO - 1499737661186 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:41 INFO - 1499737661186 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661188 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661197 Marionette TRACE 356 <- [1,1,null,{"sessionId":"5bf1cf06-ddc7-4437-b9b6-39e17fbbba0c","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661200 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:47:41 INFO - 1499737661201 Marionette TRACE 356 <- [1,2,null,{}]
18:47:41 INFO - 1499737661203 Marionette DEBUG Closed connection 356
18:47:41 INFO - 1499737661203 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50141
18:47:41 INFO - 1499737661204 Marionette DEBUG Closed connection 357
18:47:41 INFO - 1499737661205 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50142
18:47:41 INFO - 1499737661206 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:47:41 INFO - 1499737661207 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661209 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661212 Marionette TRACE 358 <- [1,1,null,{"sessionId":"944dee47-4061-4640-a528-0a2e93868a23","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661213 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49692/clicks.html"}]
18:47:41 INFO - 1499737661215 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49692/hidden.html"
18:47:41 INFO - 1499737661223 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49692/hidden.html"
18:47:41 INFO - 1499737661223 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49692/hidden.html"
18:47:41 INFO - 1499737661230 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49692/clicks.html"
18:47:41 INFO - 1499737661235 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49692/clicks.html"
18:47:41 INFO - 1499737661237 Marionette TRACE 358 <- [1,2,null,{}]
18:47:41 INFO - 1499737661244 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:47:41 INFO - 1499737661250 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"52ba1bda-c570-443c-a879-0076e4be82ed","ELEMENT":"52ba1bda-c570-443c-a879-0076e4be82ed"}}]
18:47:41 INFO - 1499737661251 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"52ba1bda-c570-443c-a879-0076e4be82ed"}]
18:47:41 INFO - 1499737661288 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49692/clicks.html"
18:47:41 INFO - 1499737661297 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49692/clicks.html"
18:47:41 INFO - 1499737661297 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49692/clicks.html"
18:47:41 INFO - 1499737661304 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49692/test.html"
18:47:41 INFO - 1499737661307 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49692/test.html"
18:47:41 INFO - 1499737661319 Marionette TRACE 358 <- [1,4,null,{}]
18:47:41 INFO - 1499737661335 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:47:41 INFO - 1499737661337 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"98d2a3bc-2307-4770-afe6-843c0ef658c3","ELEMENT":"98d2a3bc-2307-4770-afe6-843c0ef658c3"}}]
18:47:41 INFO - 1499737661338 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:47:41 INFO - 1499737661339 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:47:41 INFO - 1499737661341 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}]
18:47:41 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499737661344 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:47:41 INFO - 1499737661345 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:47:41 INFO - 1499737661346 Marionette TRACE 358 <- [1,8,null,{}]
18:47:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 188ms
18:47:41 INFO - TEST-START | test_click.py TestClick.test_container_element
18:47:41 INFO - 1499737661348 Marionette DEBUG Closed connection 358
18:47:41 INFO - 1499737661349 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50145
18:47:41 INFO - 1499737661349 Marionette DEBUG Closed connection 359
18:47:41 INFO - 1499737661350 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50146
18:47:41 INFO - 1499737661351 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:41 INFO - 1499737661352 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661354 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661357 Marionette TRACE 360 <- [1,1,null,{"sessionId":"c3d4482e-9ed3-4d75-9f31-99c2f11996ee","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661359 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:47:41 INFO - 1499737661359 Marionette TRACE 360 <- [1,2,null,{}]
18:47:41 INFO - 1499737661360 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:47:41 INFO - 1499737661360 Marionette TRACE 360 <- [1,3,null,{}]
18:47:41 INFO - 1499737661361 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:47:41 INFO - 1499737661362 Marionette TRACE 360 <- [1,4,null,{}]
18:47:41 INFO - 1499737661363 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}]
18:47:41 INFO - TEST-START: test_click.py TestClick.test_container_element1499737661366 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:47:41 INFO - 1499737661367 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:47:41 INFO - 1499737661369 Marionette TRACE 360 <- [1,6,null,{}]
18:47:41 INFO - 1499737661370 Marionette DEBUG Closed connection 360
18:47:41 INFO - 1499737661371 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50147
18:47:41 INFO - 1499737661371 Marionette DEBUG Closed connection 361
18:47:41 INFO - 1499737661372 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50148
18:47:41 INFO - 1499737661373 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:41 INFO - 1499737661374 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661376 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661379 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c6511541-9541-476d-9a74-80129fce4fb2","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661380 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:47:41 INFO - 1499737661381 Marionette TRACE 362 <- [1,2,null,{}]
18:47:41 INFO - 1499737661383 Marionette DEBUG Closed connection 362
18:47:41 INFO - 1499737661383 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50149
18:47:41 INFO - 1499737661384 Marionette DEBUG Closed connection 363
18:47:41 INFO - 1499737661385 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50150
18:47:41 INFO - 1499737661386 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:47:41 INFO - 1499737661386 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661388 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661391 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a7c0a52d-0eb5-4683-80cb-3bb13bdcb785","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661393 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"}]
18:47:41 INFO - 1499737661395 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49692/test.html"
18:47:41 INFO - 1499737661397 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49692/test.html"
18:47:41 INFO - 1499737661397 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49692/test.html"
18:47:41 INFO - 1499737661402 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"
18:47:41 INFO - 1499737661406 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"
18:47:41 INFO - 1499737661416 Marionette TRACE 364 <- [1,2,null,{}]
18:47:41 INFO - 1499737661421 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:47:41 INFO - 1499737661424 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a38dcfc-b406-44c9-bd13-cd3e593b841d","ELEMENT":"7a38dcfc-b406-44c9-bd13-cd3e593b841d"}}]
18:47:41 INFO - 1499737661430 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7a38dcfc-b406-44c9-bd13-cd3e593b841d"}]
18:47:41 INFO - 1499737661669 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:47:41 INFO - 1499737661669 Marionette TRACE 364 <- [1,4,null,{}]
18:47:41 INFO - 1499737661671 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7a38dcfc-b406-44c9-bd13-cd3e593b841d","name":"selected"}]
18:47:41 INFO - 1499737661675 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:47:41 INFO - 1499737661677 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}]
18:47:41 INFO - TEST-END: test_click.py TestClick.test_container_element1499737661679 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:47:41 INFO - 1499737661680 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:47:41 INFO - 1499737661682 Marionette TRACE 364 <- [1,7,null,{}]
18:47:41 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 335ms
18:47:41 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:47:41 INFO - 1499737661683 Marionette DEBUG Closed connection 364
18:47:41 INFO - 1499737661685 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50151
18:47:41 INFO - 1499737661685 Marionette DEBUG Closed connection 365
18:47:41 INFO - 1499737661686 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50152
18:47:41 INFO - 1499737661687 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:41 INFO - 1499737661688 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661690 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661693 Marionette TRACE 366 <- [1,1,null,{"sessionId":"81d2fcd9-9a32-4f0e-941c-d6bba3776b04","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661694 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:47:41 INFO - 1499737661695 Marionette TRACE 366 <- [1,2,null,{}]
18:47:41 INFO - 1499737661696 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:47:41 INFO - 1499737661696 Marionette TRACE 366 <- [1,3,null,{}]
18:47:41 INFO - 1499737661697 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:47:41 INFO - 1499737661697 Marionette TRACE 366 <- [1,4,null,{}]
18:47:41 INFO - 1499737661699 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}]
18:47:41 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499737661702 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:47:41 INFO - 1499737661704 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:47:41 INFO - 1499737661706 Marionette TRACE 366 <- [1,6,null,{}]
18:47:41 INFO - 1499737661707 Marionette DEBUG Closed connection 366
18:47:41 INFO - 1499737661708 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50153
18:47:41 INFO - 1499737661709 Marionette DEBUG Closed connection 367
18:47:41 INFO - 1499737661710 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50154
18:47:41 INFO - 1499737661710 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:41 INFO - 1499737661711 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661713 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661716 Marionette TRACE 368 <- [1,1,null,{"sessionId":"cc6d4e2d-50e9-4492-888d-f1bcdaaa090d","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661718 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:47:41 INFO - 1499737661719 Marionette TRACE 368 <- [1,2,null,{}]
18:47:41 INFO - 1499737661720 Marionette DEBUG Closed connection 368
18:47:41 INFO - 1499737661721 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50155
18:47:41 INFO - 1499737661721 Marionette DEBUG Closed connection 369
18:47:41 INFO - 1499737661722 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50156
18:47:41 INFO - 1499737661723 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:47:41 INFO - 1499737661724 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:41 INFO - 1499737661726 Marionette DEBUG Register listener.js for window 4294967297
18:47:41 INFO - 1499737661730 Marionette TRACE 370 <- [1,1,null,{"sessionId":"eb0eba50-4976-4e24-b31f-16af5b1208f9","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:41 INFO - 1499737661731 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"}]
18:47:41 INFO - 1499737661734 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"
18:47:41 INFO - 1499737661735 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"
18:47:41 INFO - 1499737661736 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"
18:47:41 INFO - 1499737661751 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"
18:47:41 INFO - 1499737661752 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"
18:47:41 INFO - 1499737661753 Marionette TRACE 370 <- [1,2,null,{}]
18:47:41 INFO - 1499737661756 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:47:41 INFO - 1499737661761 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ba831039-31a1-4697-a083-8ba6e7a651ef","ELEMENT":"ba831039-31a1-4697-a083-8ba6e7a651ef"}}]
18:47:41 INFO - 1499737661763 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ba831039-31a1-4697-a083-8ba6e7a651ef"}]
18:47:42 INFO - 1499737662003 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:47:42 INFO - 1499737662004 Marionette TRACE 370 <- [1,4,null,{}]
18:47:42 INFO - 1499737662005 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ba831039-31a1-4697-a083-8ba6e7a651ef","name":"selected"}]
18:47:42 INFO - 1499737662009 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:47:42 INFO - 1499737662011 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}]
18:47:42 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499737662013 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:47:42 INFO - 1499737662014 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:47:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 333ms
18:47:42 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:47:42 INFO - 1499737662015 Marionette TRACE 370 <- [1,7,null,{}]
18:47:42 INFO - 1499737662017 Marionette DEBUG Closed connection 370
18:47:42 INFO - 1499737662018 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50157
18:47:42 INFO - 1499737662019 Marionette DEBUG Closed connection 371
18:47:42 INFO - 1499737662019 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50158
18:47:42 INFO - 1499737662020 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:42 INFO - 1499737662021 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662023 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662026 Marionette TRACE 372 <- [1,1,null,{"sessionId":"dd658c72-4284-480f-a9fc-5e7c40f0d5e7","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662028 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:47:42 INFO - 1499737662028 Marionette TRACE 372 <- [1,2,null,{}]
18:47:42 INFO - 1499737662029 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:47:42 INFO - 1499737662029 Marionette TRACE 372 <- [1,3,null,{}]
18:47:42 INFO - 1499737662030 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:47:42 INFO - 1499737662031 Marionette TRACE 372 <- [1,4,null,{}]
18:47:42 INFO - 1499737662033 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}]
18:47:42 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499737662036 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:47:42 INFO - 1499737662037 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:47:42 INFO - 1499737662038 Marionette TRACE 372 <- [1,6,null,{}]
18:47:42 INFO - 1499737662040 Marionette DEBUG Closed connection 372
18:47:42 INFO - 1499737662040 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50159
18:47:42 INFO - 1499737662042 Marionette DEBUG Closed connection 373
18:47:42 INFO - 1499737662043 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50160
18:47:42 INFO - 1499737662044 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:42 INFO - 1499737662044 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662046 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662050 Marionette TRACE 374 <- [1,1,null,{"sessionId":"5dbf1b06-0936-4b49-b40b-682ae8ed54e4","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662051 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:47:42 INFO - 1499737662052 Marionette TRACE 374 <- [1,2,null,{}]
18:47:42 INFO - 1499737662054 Marionette DEBUG Closed connection 374
18:47:42 INFO - 1499737662054 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50161
18:47:42 INFO - 1499737662055 Marionette DEBUG Closed connection 375
18:47:42 INFO - 1499737662056 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50162
18:47:42 INFO - 1499737662057 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:47:42 INFO - 1499737662057 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662060 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662063 Marionette TRACE 376 <- [1,1,null,{"sessionId":"69421def-aee5-4cf9-90c5-4ba72ea86b8e","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662065 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"}]
18:47:42 INFO - 1499737662067 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"
18:47:42 INFO - 1499737662069 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"
18:47:42 INFO - 1499737662069 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"
18:47:42 INFO - 1499737662077 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"
18:47:42 INFO - 1499737662079 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"
18:47:42 INFO - 1499737662088 Marionette TRACE 376 <- [1,2,null,{}]
18:47:42 INFO - 1499737662097 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:47:42 INFO - 1499737662103 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"53e65891-de9f-4362-955e-f5422ab7374b","ELEMENT":"53e65891-de9f-4362-955e-f5422ab7374b"}}]
18:47:42 INFO - 1499737662104 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"53e65891-de9f-4362-955e-f5422ab7374b"}]
18:47:42 INFO - 1499737662344 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:47:42 INFO - 1499737662344 Marionette TRACE 376 <- [1,4,null,{}]
18:47:42 INFO - 1499737662347 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}]
18:47:42 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499737662349 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:47:42 INFO - 1499737662350 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:47:42 INFO - 1499737662352 Marionette TRACE 376 <- [1,6,null,{}]
18:47:42 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 336ms
18:47:42 INFO - 1499737662353 Marionette DEBUG Closed connection 376
18:47:42 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:47:42 INFO - 1499737662355 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50163
18:47:42 INFO - 1499737662356 Marionette DEBUG Closed connection 377
18:47:42 INFO - 1499737662357 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50164
18:47:42 INFO - 1499737662358 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:42 INFO - 1499737662366 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662369 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662372 Marionette TRACE 378 <- [1,1,null,{"sessionId":"e0db60ce-5220-46a9-9309-604026532b56","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662373 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:47:42 INFO - 1499737662373 Marionette TRACE 378 <- [1,2,null,{}]
18:47:42 INFO - 1499737662376 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:47:42 INFO - 1499737662376 Marionette TRACE 378 <- [1,3,null,{}]
18:47:42 INFO - 1499737662377 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:47:42 INFO - 1499737662378 Marionette TRACE 378 <- [1,4,null,{}]
18:47:42 INFO - 1499737662379 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}]
18:47:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499737662382 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:47:42 INFO - 1499737662383 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:47:42 INFO - 1499737662385 Marionette TRACE 378 <- [1,6,null,{}]
18:47:42 INFO - 1499737662386 Marionette DEBUG Closed connection 378
18:47:42 INFO - 1499737662386 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50165
18:47:42 INFO - 1499737662387 Marionette DEBUG Closed connection 379
18:47:42 INFO - 1499737662388 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50166
18:47:42 INFO - 1499737662389 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:42 INFO - 1499737662390 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662392 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662395 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8f8d11a3-a953-4471-bea6-46e396b6cf57","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662396 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:47:42 INFO - 1499737662397 Marionette TRACE 380 <- [1,2,null,{}]
18:47:42 INFO - 1499737662399 Marionette DEBUG Closed connection 380
18:47:42 INFO - 1499737662399 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50167
18:47:42 INFO - 1499737662400 Marionette DEBUG Closed connection 381
18:47:42 INFO - 1499737662401 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50168
18:47:42 INFO - 1499737662402 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:47:42 INFO - 1499737662402 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662404 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662407 Marionette TRACE 382 <- [1,1,null,{"sessionId":"911e6ec3-45c5-49b1-847e-a34221612867","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662408 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"}]
18:47:42 INFO - 1499737662410 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"
18:47:42 INFO - 1499737662413 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"
18:47:42 INFO - 1499737662413 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"
18:47:42 INFO - 1499737662421 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"
18:47:42 INFO - 1499737662426 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"
18:47:42 INFO - 1499737662430 Marionette TRACE 382 <- [1,2,null,{}]
18:47:42 INFO - 1499737662433 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:47:42 INFO - 1499737662437 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf4419ed-8add-45be-95bb-a70527bfe2a4","ELEMENT":"bf4419ed-8add-45be-95bb-a70527bfe2a4"}}]
18:47:42 INFO - 1499737662438 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"bf4419ed-8add-45be-95bb-a70527bfe2a4"}]
18:47:42 INFO - 1499737662678 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:47:42 INFO - 1499737662679 Marionette TRACE 382 <- [1,4,null,{}]
18:47:42 INFO - 1499737662681 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}]
18:47:42 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499737662683 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:47:42 INFO - 1499737662684 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:47:42 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 333ms
18:47:42 INFO - TEST-START | test_click.py TestClick.test_input_file
18:47:42 INFO - 1499737662686 Marionette TRACE 382 <- [1,6,null,{}]
18:47:42 INFO - 1499737662687 Marionette DEBUG Closed connection 382
18:47:42 INFO - 1499737662688 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50169
18:47:42 INFO - 1499737662689 Marionette DEBUG Closed connection 383
18:47:42 INFO - 1499737662690 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50170
18:47:42 INFO - 1499737662691 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:42 INFO - 1499737662691 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662694 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662697 Marionette TRACE 384 <- [1,1,null,{"sessionId":"9762d6d7-7b72-400f-aae5-f02d006fea2b","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662698 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:47:42 INFO - 1499737662698 Marionette TRACE 384 <- [1,2,null,{}]
18:47:42 INFO - 1499737662699 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:47:42 INFO - 1499737662699 Marionette TRACE 384 <- [1,3,null,{}]
18:47:42 INFO - 1499737662700 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:47:42 INFO - 1499737662701 Marionette TRACE 384 <- [1,4,null,{}]
18:47:42 INFO - 1499737662702 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}]
18:47:42 INFO - TEST-START: test_click.py TestClick.test_input_file1499737662705 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:47:42 INFO - 1499737662706 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:47:42 INFO - 1499737662707 Marionette TRACE 384 <- [1,6,null,{}]
18:47:42 INFO - 1499737662709 Marionette DEBUG Closed connection 384
18:47:42 INFO - 1499737662710 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50171
18:47:42 INFO - 1499737662710 Marionette DEBUG Closed connection 385
18:47:42 INFO - 1499737662711 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50172
18:47:42 INFO - 1499737662712 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:42 INFO - 1499737662713 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662715 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662718 Marionette TRACE 386 <- [1,1,null,{"sessionId":"8c99e815-2f2d-49a3-8cfe-607ec0051495","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662719 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:47:42 INFO - 1499737662720 Marionette TRACE 386 <- [1,2,null,{}]
18:47:42 INFO - 1499737662722 Marionette DEBUG Closed connection 386
18:47:42 INFO - 1499737662722 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50173
18:47:42 INFO - 1499737662723 Marionette DEBUG Closed connection 387
18:47:42 INFO - 1499737662724 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50174
18:47:42 INFO - 1499737662725 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:47:42 INFO - 1499737662726 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662728 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662731 Marionette TRACE 388 <- [1,1,null,{"sessionId":"6cbe1d7e-1b28-4204-ac57-c08316d4741f","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662732 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:47:42 INFO - 1499737662734 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"
18:47:42 INFO - 1499737662736 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"
18:47:42 INFO - 1499737662736 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"
18:47:42 INFO - 1499737662742 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:47:42 INFO - 1499737662747 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:47:42 INFO - 1499737662756 Marionette TRACE 388 <- [1,2,null,{}]
18:47:42 INFO - 1499737662763 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:47:42 INFO - 1499737662767 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d094c4c9-4c41-4177-ab4a-3ff0a58b1838","ELEMENT":"d094c4c9-4c41-4177-ab4a-3ff0a58b1838"}}]
18:47:42 INFO - 1499737662768 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d094c4c9-4c41-4177-ab4a-3ff0a58b1838"}]
18:47:42 INFO - 1499737662799 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
18:47:42 INFO - 1499737662801 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}]
18:47:42 INFO - TEST-END: test_click.py TestClick.test_input_file1499737662804 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:47:42 INFO - 1499737662804 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:47:42 INFO - 1499737662806 Marionette TRACE 388 <- [1,6,null,{}]
18:47:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 120ms
18:47:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:47:42 INFO - 1499737662807 Marionette DEBUG Closed connection 388
18:47:42 INFO - 1499737662809 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50175
18:47:42 INFO - 1499737662810 Marionette DEBUG Closed connection 389
18:47:42 INFO - 1499737662811 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50176
18:47:42 INFO - 1499737662812 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:42 INFO - 1499737662812 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662815 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662818 Marionette TRACE 390 <- [1,1,null,{"sessionId":"f52464a6-643a-464e-ac5d-d9d8a3ce8955","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662819 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:47:42 INFO - 1499737662819 Marionette TRACE 390 <- [1,2,null,{}]
18:47:42 INFO - 1499737662820 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:47:42 INFO - 1499737662821 Marionette TRACE 390 <- [1,3,null,{}]
18:47:42 INFO - 1499737662821 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:47:42 INFO - 1499737662822 Marionette TRACE 390 <- [1,4,null,{}]
18:47:42 INFO - 1499737662824 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}]
18:47:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499737662827 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:47:42 INFO - 1499737662828 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:47:42 INFO - 1499737662829 Marionette TRACE 390 <- [1,6,null,{}]
18:47:42 INFO - 1499737662831 Marionette DEBUG Closed connection 390
18:47:42 INFO - 1499737662831 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50177
18:47:42 INFO - 1499737662832 Marionette DEBUG Closed connection 391
18:47:42 INFO - 1499737662833 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50178
18:47:42 INFO - 1499737662834 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:42 INFO - 1499737662834 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662836 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662839 Marionette TRACE 392 <- [1,1,null,{"sessionId":"52ee7e97-4df7-44ed-8b4b-9f0f1e1eea65","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662841 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:47:42 INFO - 1499737662842 Marionette TRACE 392 <- [1,2,null,{}]
18:47:42 INFO - 1499737662843 Marionette DEBUG Closed connection 392
18:47:42 INFO - 1499737662844 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50179
18:47:42 INFO - 1499737662844 Marionette DEBUG Closed connection 393
18:47:42 INFO - 1499737662845 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50180
18:47:42 INFO - 1499737662846 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:47:42 INFO - 1499737662847 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:42 INFO - 1499737662849 Marionette DEBUG Register listener.js for window 4294967297
18:47:42 INFO - 1499737662852 Marionette TRACE 394 <- [1,1,null,{"sessionId":"750e8f2e-9e3e-4e8b-bb03-5e008c156593","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:42 INFO - 1499737662853 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"}]
18:47:42 INFO - 1499737662855 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:47:42 INFO - 1499737662857 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:47:42 INFO - 1499737662857 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:47:42 INFO - 1499737662864 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"
18:47:42 INFO - 1499737662866 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"
18:47:42 INFO - 1499737662869 Marionette TRACE 394 <- [1,2,null,{}]
18:47:42 INFO - 1499737662872 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:47:42 INFO - 1499737662880 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cfe8dc6b-5880-4df4-b59f-86bc6b873549","ELEMENT":"cfe8dc6b-5880-4df4-b59f-86bc6b873549"}}]
18:47:42 INFO - 1499737662882 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:47:42 INFO - 1499737662884 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c14477ca-8675-436e-95db-c99e6fb4cde0","ELEMENT":"c14477ca-8675-436e-95db-c99e6fb4cde0"}}]
18:47:42 INFO - 1499737662885 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"cfe8dc6b-5880-4df4-b59f-86bc6b873549"}]
18:47:43 INFO - 1499737663121 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:47:43 INFO - 1499737663122 Marionette TRACE 394 <- [1,5,null,{}]
18:47:43 INFO - 1499737663123 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c14477ca-8675-436e-95db-c99e6fb4cde0"}]
18:47:43 INFO - 1499737663137 Marionette TRACE 394 <- [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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
18:47:43 INFO - 1499737663139 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:47:43 INFO - 1499737663142 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:47:43 INFO - 1499737663144 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}]
18:47:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499737663146 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:47:43 INFO - 1499737663147 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:47:43 INFO - 1499737663149 Marionette TRACE 394 <- [1,9,null,{}]
18:47:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 342ms
18:47:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:47:43 INFO - 1499737663150 Marionette DEBUG Closed connection 394
18:47:43 INFO - 1499737663152 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50181
18:47:43 INFO - 1499737663153 Marionette DEBUG Closed connection 395
18:47:43 INFO - 1499737663153 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50182
18:47:43 INFO - 1499737663154 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:43 INFO - 1499737663155 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:43 INFO - 1499737663157 Marionette DEBUG Register listener.js for window 4294967297
18:47:43 INFO - 1499737663161 Marionette TRACE 396 <- [1,1,null,{"sessionId":"8b877ffb-fa16-4d2b-b3dc-8583d1d15835","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:43 INFO - 1499737663162 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:47:43 INFO - 1499737663163 Marionette TRACE 396 <- [1,2,null,{}]
18:47:43 INFO - 1499737663164 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:47:43 INFO - 1499737663164 Marionette TRACE 396 <- [1,3,null,{}]
18:47:43 INFO - 1499737663165 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:47:43 INFO - 1499737663165 Marionette TRACE 396 <- [1,4,null,{}]
18:47:43 INFO - 1499737663167 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}]
18:47:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499737663170 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:47:43 INFO - 1499737663171 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:47:43 INFO - 1499737663172 Marionette TRACE 396 <- [1,6,null,{}]
18:47:43 INFO - 1499737663174 Marionette DEBUG Closed connection 396
18:47:43 INFO - 1499737663175 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50183
18:47:43 INFO - 1499737663175 Marionette DEBUG Closed connection 397
18:47:43 INFO - 1499737663176 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50184
18:47:43 INFO - 1499737663177 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:47:43 INFO - 1499737663178 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:43 INFO - 1499737663180 Marionette DEBUG Register listener.js for window 4294967297
18:47:43 INFO - 1499737663183 Marionette TRACE 398 <- [1,1,null,{"sessionId":"988c544d-4478-4050-9ed3-28177c36167c","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:43 INFO - 1499737663184 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:47:43 INFO - 1499737663186 Marionette TRACE 398 <- [1,2,null,{}]
18:47:43 INFO - 1499737663187 Marionette DEBUG Closed connection 398
18:47:43 INFO - 1499737663188 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50185
18:47:43 INFO - 1499737663188 Marionette DEBUG Closed connection 399
18:47:43 INFO - 1499737663189 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50186
18:47:43 INFO - 1499737663190 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:47:43 INFO - 1499737663191 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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}
18:47:43 INFO - 1499737663193 Marionette DEBUG Register listener.js for window 4294967297
18:47:43 INFO - 1499737663197 Marionette TRACE 400 <- [1,1,null,{"sessionId":"7f94ed1f-6494-462d-b6a5-4c886797383f","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":5628,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmphapyyc.mozrunner","moz:accessibilityChecks":false}}]
18:47:43 INFO - 1499737663198 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"}]
18:47:43 INFO - 1499737663200 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"
18:47:43 INFO - 1499737663202 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"
18:47:43 INFO - 1499737663202 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"
18:47:43 INFO - 1499737663211 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"
18:47:43 INFO - 1499737663212 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"
18:47:43 INFO - 1499737663219 Marionette TRACE 400 <- [1,2,null,{}]
18:47:43 INFO - 1499737663222 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:47:43 INFO - 1499737663228 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a2595c2b-7995-4172-afb4-ecba8caa7329","ELEMENT":"a2595c2b-7995-4172-afb4-ecba8caa7329"}}]
18:47:43 INFO - 1499737663230 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a2595c2b-7995-4172-afb4-ecba8caa7329"}]
18:47:43 INFO - 1499737663252 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:47:43 INFO - 1499737663253 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a2595c2b-7995-4172-afb4-ecba8caa7329"}]
18:47:43 INFO - 1499737663268 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element